2021-04-27T17:14:58.682Z,1619543698.682 [Supervisor](DEBUG): Initializing supervisor.
2021-04-27T17:14:58.696Z,1619543698.696 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-27T17:14:58.696Z,1619543698.696 [SyncHandler](INFO): Protected caller Thread ID is 832
2021-04-27T17:14:58.697Z,1619543698.697 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-04-27T17:14:58.708Z,1619543698.708 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-04-27T17:14:58.708Z,1619543698.708 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 833
2021-04-27T17:14:58.711Z,1619543698.711 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-04-27T17:14:58.750Z,1619543698.750 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-04-27T17:14:58.760Z,1619543698.760 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-04-27T17:14:58.760Z,1619543698.760 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834
2021-04-27T17:14:58.761Z,1619543698.761 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-04-27T17:14:58.772Z,1619543698.772 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-04-27T17:14:58.772Z,1619543698.772 [logger ThreadHandler](INFO): Protected caller Thread ID is 835
2021-04-27T17:14:58.774Z,1619543698.774 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-04-27T17:14:58.775Z,1619543698.775 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-04-27T17:14:58.779Z,1619543698.779 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-04-27T17:14:59.564Z,1619543699.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-04-27T17:14:59.566Z,1619543699.566 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-04-27T17:14:59.704Z,1619543699.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-04-27T17:14:59.706Z,1619543699.706 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-04-27T17:14:59.787Z,1619543699.787 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-04-27T17:14:59.986Z,1619543699.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-04-27T17:14:59.988Z,1619543699.988 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-04-27T17:15:00.084Z,1619543700.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2021-04-27T17:15:00.086Z,1619543700.086 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-04-27T17:15:00.180Z,1619543700.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-04-27T17:15:00.181Z,1619543700.181 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-04-27T17:15:00.556Z,1619543700.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-04-27T17:15:00.558Z,1619543700.558 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-04-27T17:15:00.662Z,1619543700.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-04-27T17:15:00.664Z,1619543700.664 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-04-27T17:15:01.154Z,1619543701.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-04-27T17:15:01.156Z,1619543701.156 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-04-27T17:15:01.341Z,1619543701.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-04-27T17:15:01.342Z,1619543701.342 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-04-27T17:15:01.997Z,1619543701.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-04-27T17:15:01.998Z,1619543701.998 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-04-27T17:15:02.207Z,1619543702.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-04-27T17:15:02.208Z,1619543702.208 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-04-27T17:15:02.347Z,1619543702.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-04-27T17:15:02.347Z,1619543702.347 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-04-27T17:15:02.985Z,1619543702.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-04-27T17:15:02.987Z,1619543702.987 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-04-27T17:15:03.304Z,1619543703.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-04-27T17:15:03.306Z,1619543703.306 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-04-27T17:15:03.506Z,1619543703.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-04-27T17:15:03.508Z,1619543703.508 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-04-27T17:15:03.510Z,1619543703.510 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-04-27T17:15:03.695Z,1619543703.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-04-27T17:15:03.777Z,1619543703.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-04-27T17:15:03.923Z,1619543703.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-04-27T17:15:04.007Z,1619543704.007 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-04-27T17:15:04.255Z,1619543704.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-04-27T17:15:04.257Z,1619543704.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-04-27T17:15:04.360Z,1619543704.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-04-27T17:15:04.466Z,1619543704.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-04-27T17:15:04.558Z,1619543704.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-04-27T17:15:04.713Z,1619543704.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-04-27T17:15:04.809Z,1619543704.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-04-27T17:15:04.908Z,1619543704.908 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-04-27T17:15:04.911Z,1619543704.911 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-04-27T17:15:04.942Z,1619543704.942 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-04-27T17:15:04.942Z,1619543704.942 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-04-27T17:15:05.038Z,1619543705.038 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-04-27T17:15:05.039Z,1619543705.039 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-04-27T17:15:05.102Z,1619543705.102 [VerticalControl](DEBUG): Construct VerticalControl.
2021-04-27T17:15:05.159Z,1619543705.159 [VerticalControl] Loaded
2021-04-27T17:15:05.159Z,1619543705.159 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-04-27T17:15:05.160Z,1619543705.160 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-04-27T17:15:05.197Z,1619543705.197 [HorizontalControl] Loaded
2021-04-27T17:15:05.197Z,1619543705.197 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-04-27T17:15:05.198Z,1619543705.198 [SpeedControl](DEBUG): Construct SpeedControl.
2021-04-27T17:15:05.201Z,1619543705.201 [SpeedControl] Loaded
2021-04-27T17:15:05.201Z,1619543705.201 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-04-27T17:15:05.202Z,1619543705.202 [LoopControl](DEBUG): Construct LoopControl.
2021-04-27T17:15:05.202Z,1619543705.202 [LoopControl] Loaded
2021-04-27T17:15:05.202Z,1619543705.202 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-04-27T17:15:05.203Z,1619543705.203 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-04-27T17:15:05.203Z,1619543705.203 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-04-27T17:15:05.359Z,1619543705.359 [BuoyancyServo] Loaded
2021-04-27T17:15:05.359Z,1619543705.359 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-04-27T17:15:05.375Z,1619543705.375 [ElevatorServo] Loaded
2021-04-27T17:15:05.375Z,1619543705.375 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-04-27T17:15:05.390Z,1619543705.390 [MassServo] Loaded
2021-04-27T17:15:05.391Z,1619543705.391 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-04-27T17:15:05.405Z,1619543705.405 [RudderServo] Loaded
2021-04-27T17:15:05.406Z,1619543705.406 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-04-27T17:15:05.420Z,1619543705.420 [ThrusterServo] Loaded
2021-04-27T17:15:05.421Z,1619543705.421 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-04-27T17:15:05.421Z,1619543705.421 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-04-27T17:15:05.422Z,1619543705.422 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-04-27T17:15:05.473Z,1619543705.473 [DepthRateCalculator] Loaded
2021-04-27T17:15:05.473Z,1619543705.473 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-04-27T17:15:05.476Z,1619543705.476 [PitchRateCalculator] Loaded
2021-04-27T17:15:05.476Z,1619543705.476 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-04-27T17:15:05.483Z,1619543705.483 [SpeedCalculator] Loaded
2021-04-27T17:15:05.484Z,1619543705.484 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-04-27T17:15:05.495Z,1619543705.495 [TempGradientCalculator] Loaded
2021-04-27T17:15:05.495Z,1619543705.495 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-04-27T17:15:05.497Z,1619543705.497 [YawRateCalculator] Loaded
2021-04-27T17:15:05.498Z,1619543705.498 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-04-27T17:15:05.512Z,1619543705.512 [ElevatorOffsetCalculator] Loaded
2021-04-27T17:15:05.512Z,1619543705.512 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-04-27T17:15:05.513Z,1619543705.513 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-04-27T17:15:05.513Z,1619543705.513 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-04-27T17:15:05.609Z,1619543705.609 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-04-27T17:15:05.609Z,1619543705.609 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-04-27T17:15:05.620Z,1619543705.620 [NavChart] Loaded
2021-04-27T17:15:05.620Z,1619543705.620 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-04-27T17:15:05.624Z,1619543705.624 [UniversalFixResidualReporter] Loaded
2021-04-27T17:15:05.624Z,1619543705.624 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-04-27T17:15:05.624Z,1619543705.624 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-04-27T17:15:05.625Z,1619543705.625 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-04-27T17:15:06.156Z,1619543706.156 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2021-04-27T17:15:06.159Z,1619543706.159 [AHRS_M2](INFO): created writer for : platform_orientation
2021-04-27T17:15:06.161Z,1619543706.161 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2021-04-27T17:15:06.164Z,1619543706.164 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2021-04-27T17:15:06.164Z,1619543706.164 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2021-04-27T17:15:06.167Z,1619543706.167 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2021-04-27T17:15:06.167Z,1619543706.167 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2021-04-27T17:15:06.170Z,1619543706.170 [AHRS_M2](INFO): created writer for : platform_roll_angle
2021-04-27T17:15:06.252Z,1619543706.252 [AHRS_M2] Loaded
2021-04-27T17:15:06.252Z,1619543706.252 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-04-27T17:15:06.392Z,1619543706.392 [DataOverHttps] Loaded
2021-04-27T17:15:06.392Z,1619543706.392 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-04-27T17:15:06.393Z,1619543706.393 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408514E0
2021-04-27T17:15:06.394Z,1619543706.394 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 917
2021-04-27T17:15:06.410Z,1619543706.410 [Depth_Keller] Loaded
2021-04-27T17:15:06.410Z,1619543706.410 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-04-27T17:15:06.413Z,1619543706.413 [DropWeight] Loaded
2021-04-27T17:15:06.413Z,1619543706.413 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-04-27T17:15:06.444Z,1619543706.444 [DVL_micro] Loaded
2021-04-27T17:15:06.444Z,1619543706.444 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2021-04-27T17:15:06.496Z,1619543706.496 [NAL9602] Loaded
2021-04-27T17:15:06.496Z,1619543706.496 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-04-27T17:15:06.522Z,1619543706.522 [Onboard] Loaded
2021-04-27T17:15:06.522Z,1619543706.522 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-04-27T17:15:06.523Z,1619543706.523 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408814E0
2021-04-27T17:15:06.524Z,1619543706.524 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 918
2021-04-27T17:15:06.534Z,1619543706.534 [Radio_Surface] Loaded
2021-04-27T17:15:06.534Z,1619543706.534 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-04-27T17:15:06.535Z,1619543706.535 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2021-04-27T17:15:06.535Z,1619543706.535 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 919
2021-04-27T17:15:06.609Z,1619543706.609 [DAT] Loaded
2021-04-27T17:15:06.610Z,1619543706.610 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-04-27T17:15:07.382Z,1619543707.382 [BPC1] Loaded
2021-04-27T17:15:07.382Z,1619543707.382 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-04-27T17:15:07.383Z,1619543707.383 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-04-27T17:15:07.384Z,1619543707.384 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-04-27T17:15:07.810Z,1619543707.810 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-04-27T17:15:07.811Z,1619543707.811 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-04-27T17:15:08.346Z,1619543708.346 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-04-27T17:15:08.348Z,1619543708.348 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-04-27T17:15:08.389Z,1619543708.389 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-04-27T17:15:08.389Z,1619543708.389 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-04-27T17:15:08.667Z,1619543708.667 [Aanderaa_O2] Loaded
2021-04-27T17:15:08.668Z,1619543708.668 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2021-04-27T17:15:08.686Z,1619543708.686 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2021-04-27T17:15:08.689Z,1619543708.689 [CTD_Seabird](INFO): created writer for : sea_water_density
2021-04-27T17:15:08.689Z,1619543708.689 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2021-04-27T17:15:08.692Z,1619543708.692 [CTD_Seabird](INFO): created writer for : depth
2021-04-27T17:15:08.692Z,1619543708.692 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2021-04-27T17:15:08.695Z,1619543708.695 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2021-04-27T17:15:08.695Z,1619543708.695 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2021-04-27T17:15:08.698Z,1619543708.698 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2021-04-27T17:15:08.699Z,1619543708.699 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2021-04-27T17:15:08.701Z,1619543708.701 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2021-04-27T17:15:08.702Z,1619543708.702 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2021-04-27T17:15:08.705Z,1619543708.705 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2021-04-27T17:15:08.705Z,1619543708.705 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2021-04-27T17:15:08.708Z,1619543708.708 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2021-04-27T17:15:08.721Z,1619543708.721 [CTD_Seabird] Loaded
2021-04-27T17:15:08.721Z,1619543708.721 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-04-27T17:15:08.722Z,1619543708.722 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2021-04-27T17:15:08.723Z,1619543708.723 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 920
2021-04-27T17:15:08.752Z,1619543708.752 [ESPComponent] Loaded
2021-04-27T17:15:08.752Z,1619543708.752 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-04-27T17:15:08.768Z,1619543708.768 [PAR_Licor] Loaded
2021-04-27T17:15:08.768Z,1619543708.768 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-04-27T17:15:08.780Z,1619543708.780 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2021-04-27T17:15:08.781Z,1619543708.781 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2021-04-27T17:15:08.783Z,1619543708.783 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2021-04-27T17:15:08.783Z,1619543708.783 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2021-04-27T17:15:08.785Z,1619543708.785 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2021-04-27T17:15:08.785Z,1619543708.785 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2021-04-27T17:15:08.787Z,1619543708.787 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2021-04-27T17:15:08.787Z,1619543708.787 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2021-04-27T17:15:08.789Z,1619543708.789 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2021-04-27T17:15:08.789Z,1619543708.789 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2021-04-27T17:15:08.791Z,1619543708.791 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2021-04-27T17:15:08.791Z,1619543708.791 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2021-04-27T17:15:08.793Z,1619543708.793 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2021-04-27T17:15:08.793Z,1619543708.793 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2021-04-27T17:15:08.795Z,1619543708.795 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.797Z,1619543708.797 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.798Z,1619543708.798 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.798Z,1619543708.798 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.800Z,1619543708.800 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.800Z,1619543708.800 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.802Z,1619543708.802 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.802Z,1619543708.802 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-04-27T17:15:08.804Z,1619543708.804 [WetLabsBB2FL] Loaded
2021-04-27T17:15:08.804Z,1619543708.804 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-04-27T17:15:08.805Z,1619543708.805 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2021-04-27T17:15:08.806Z,1619543708.806 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 921
2021-04-27T17:15:08.806Z,1619543708.806 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-04-27T17:15:08.807Z,1619543708.807 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-04-27T17:15:09.135Z,1619543709.135 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-04-27T17:15:09.136Z,1619543709.136 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-04-27T17:15:09.293Z,1619543709.293 [SBIT](DEBUG): Construct Startup Built In Test.
2021-04-27T17:15:09.302Z,1619543709.302 [SBIT] Loaded
2021-04-27T17:15:09.302Z,1619543709.302 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-04-27T17:15:09.303Z,1619543709.303 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-04-27T17:15:09.315Z,1619543709.315 [IBIT] Loaded
2021-04-27T17:15:09.315Z,1619543709.315 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-04-27T17:15:09.318Z,1619543709.318 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-04-27T17:15:09.414Z,1619543709.414 [CBIT] Loaded
2021-04-27T17:15:09.415Z,1619543709.415 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-04-27T17:15:09.415Z,1619543709.415 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-04-27T17:15:09.419Z,1619543709.419 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-04-27T17:15:09.420Z,1619543709.420 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-04-27T17:15:09.428Z,1619543709.428 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-04-27T17:15:09.429Z,1619543709.429 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2021-04-27T17:15:09.429Z,1619543709.429 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 922
2021-04-27T17:15:09.434Z,1619543709.434 [Supervisor](INFO): Main Thread ID is 827
2021-04-27T17:15:09.434Z,1619543709.434 [Supervisor](DEBUG): Running supervisor.
2021-04-27T17:15:09.434Z,1619543709.434 [CommandLine ThreadHandler](INFO): Handler Thread ID is 923
2021-04-27T17:15:09.437Z,1619543709.437 [controlThread ThreadHandler](INFO): Handler Thread ID is 924
2021-04-27T17:15:09.437Z,1619543709.437 [controlThread](DEBUG): Initializing ControlThread
2021-04-27T17:15:09.438Z,1619543709.438 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-04-27T17:15:09.439Z,1619543709.439 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-04-27T17:15:09.440Z,1619543709.440 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-04-27T17:15:09.441Z,1619543709.441 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-04-27T17:15:09.442Z,1619543709.442 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-04-27T17:15:09.442Z,1619543709.442 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-04-27T17:15:09.443Z,1619543709.443 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-04-27T17:15:09.443Z,1619543709.443 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-04-27T17:15:09.443Z,1619543709.443 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-04-27T17:15:09.444Z,1619543709.444 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-04-27T17:15:09.445Z,1619543709.445 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-04-27T17:15:09.445Z,1619543709.445 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-04-27T17:15:09.452Z,1619543709.452 [SBIT](INFO): Initialize SBIT Component.
2021-04-27T17:15:09.453Z,1619543709.453 [SBIT](IMPORTANT): git: 2020-11-24-6-gcb638d650
2021-04-27T17:15:09.453Z,1619543709.453 [SBIT](INFO): git hash: cb638d650ac65501896d406bf5988c2fe747cbc6
2021-04-27T17:15:09.453Z,1619543709.453 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-04-27T17:15:09.454Z,1619543709.454 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020
2021-04-27T17:15:09.455Z,1619543709.455 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2021-04-27T17:15:09.456Z,1619543709.455 [IBIT](INFO): Initialize IBIT Component.
2021-04-27T17:15:09.457Z,1619543709.457 [CBIT](DEBUG): Initialize CBIT Component.
2021-04-27T17:15:09.457Z,1619543709.457 [logger ThreadHandler](INFO): Handler Thread ID is 925
2021-04-27T17:15:09.468Z,1619543709.468 [CBIT](DEBUG): Initialized mux pins.
2021-04-27T17:15:09.468Z,1619543709.468 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2021-04-27T17:15:09.468Z,1619543709.468 [CBIT](DEBUG): Initializing the watchdog timer.
2021-04-27T17:15:09.476Z,1619543709.476 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 926
2021-04-27T17:15:09.477Z,1619543709.477 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-04-27T17:15:09.488Z,1619543709.488 [Onboard ThreadHandler](INFO): Handler Thread ID is 927
2021-04-27T17:15:09.492Z,1619543709.492 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2021-04-27T17:15:09.493Z,1619543709.493 [CBIT](DEBUG): Initializing heartbeat.
2021-04-27T17:15:09.506Z,1619543709.506 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 928
2021-04-27T17:15:09.524Z,1619543709.524 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 929
2021-04-27T17:15:09.525Z,1619543709.525 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-27T17:15:09.529Z,1619543709.529 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 931
2021-04-27T17:15:09.533Z,1619543709.533 [WetLabsBB2FL](INFO): Powering down
2021-04-27T17:15:09.564Z,1619543709.564 [CBIT](DEBUG): Deactivating GF circuits.
2021-04-27T17:15:09.564Z,1619543709.564 [CBIT](DEBUG): Deactivating emergency mode.
2021-04-27T17:15:09.600Z,1619543709.600 [CBIT](DEBUG): Backplane powered.
2021-04-27T17:15:09.642Z,1619543709.642 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-04-27T17:15:09.653Z,1619543709.653 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 932
2021-04-27T17:15:09.763Z,1619543709.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-04-27T17:15:09.763Z,1619543709.763 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-04-27T17:15:09.763Z,1619543709.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-04-27T17:15:09.764Z,1619543709.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-04-27T17:15:09.764Z,1619543709.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-04-27T17:15:09.764Z,1619543709.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-04-27T17:15:09.764Z,1619543709.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-04-27T17:15:09.765Z,1619543709.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-04-27T17:15:09.765Z,1619543709.765 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-04-27T17:15:09.765Z,1619543709.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-04-27T17:15:09.765Z,1619543709.765 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-04-27T17:15:09.765Z,1619543709.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-04-27T17:15:09.765Z,1619543709.765 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-04-27T17:15:09.765Z,1619543709.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-04-27T17:15:09.766Z,1619543709.766 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-04-27T17:15:09.766Z,1619543709.766 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-04-27T17:15:09.785Z,1619543709.785 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-27T17:15:09.866Z,1619543709.866 [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>


2021-04-27T17:15:09.867Z,1619543709.867 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-04-27T17:15:09.912Z,1619543709.912 [Radio_Surface](INFO): Powering up
2021-04-27T17:15:09.976Z,1619543709.976 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-04-27T17:15:09.977Z,1619543709.977 [Default:A.Wait](DEBUG): Construct Wait.
2021-04-27T17:15:09.978Z,1619543709.978 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-27T17:15:10.005Z,1619543710.005 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-04-27T17:15:10.007Z,1619543710.007 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-04-27T17:15:10.029Z,1619543710.029 [Default:E.Execute](DEBUG): Construct Execute.
2021-04-27T17:15:10.052Z,1619543710.052 [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>


2021-04-27T17:15:10.057Z,1619543710.057 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,ESPComponent,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,
2021-04-27T17:15:10.062Z,1619543710.062 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-04-27T17:15:10.165Z,1619543710.165 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2021-04-27T17:15:10.175Z,1619543710.175 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-27T17:15:10.175Z,1619543710.175 [DAT](INFO): Powering up
2021-04-27T17:15:10.175Z,1619543710.175 [DAT](DEBUG): Initializing DAT.
2021-04-27T17:15:10.230Z,1619543710.230 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-04-27T17:15:10.236Z,1619543710.236 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-27T17:15:10.291Z,1619543710.291 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-27T17:15:10.296Z,1619543710.296 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-27T17:15:10.298Z,1619543710.298 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-04-27T17:15:10.308Z,1619543710.308 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-04-27T17:15:10.309Z,1619543710.309 [MassServo](DEBUG): Initializing EZServoServo.
2021-04-27T17:15:10.320Z,1619543710.320 [MassServo](DEBUG): Initializing MassServo.
2021-04-27T17:15:10.321Z,1619543710.321 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-27T17:15:10.332Z,1619543710.332 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-27T17:15:10.333Z,1619543710.333 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-04-27T17:15:10.342Z,1619543710.342 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-04-27T17:15:10.345Z,1619543710.345 [CommandLine](FAULT): Scheduling is paused
2021-04-27T17:15:10.345Z,1619543710.345 [CBIT](INFO): Critical error at 20210427T171509
2021-04-27T17:15:10.346Z,1619543710.346 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-04-27T17:15:11.216Z,1619543711.216 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-04-27T17:15:11.216Z,1619543711.216 [RudderServo](FAULT): Rudder failed to initialize
2021-04-27T17:15:11.216Z,1619543711.216 [RudderServo] Communications Fault, FailCount= 1
2021-04-27T17:15:11.216Z,1619543711.216 [RudderServo](ERROR): Communications Fault
2021-04-27T17:15:11.316Z,1619543711.316 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-04-27T17:15:11.465Z,1619543711.465 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-27T17:15:11.465Z,1619543711.465 [RudderServo](INFO): Powering down
2021-04-27T17:15:12.156Z,1619543712.156 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-27T17:15:12.277Z,1619543712.277 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-27T17:15:12.281Z,1619543712.281 [CBIT](INFO): Clearing failed state for component RudderServo
2021-04-27T17:15:12.281Z,1619543712.281 [RudderServo] No Fault, FailCount= 1
2021-04-27T17:15:14.016Z,1619543714.016 [MassServo](ERROR): getPosition uart error serial timeout
2021-04-27T17:15:14.016Z,1619543714.016 [MassServo](FAULT): Invalid EZ Servo response:""
2021-04-27T17:15:14.017Z,1619543714.017 [MassServo] Communications Fault, FailCount= 1
2021-04-27T17:15:14.017Z,1619543714.017 [MassServo](ERROR): Communications Fault
2021-04-27T17:15:14.071Z,1619543714.071 [CBIT](ERROR): Communications Fault in component: MassServo
2021-04-27T17:15:14.224Z,1619543714.224 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-27T17:15:14.225Z,1619543714.225 [MassServo](INFO): Powering down
2021-04-27T17:15:15.060Z,1619543715.060 [MassServo](DEBUG): Initializing EZServoServo.
2021-04-27T17:15:15.181Z,1619543715.181 [MassServo](DEBUG): Initializing MassServo.
2021-04-27T17:15:15.185Z,1619543715.185 [CBIT](INFO): Clearing failed state for component MassServo
2021-04-27T17:15:15.185Z,1619543715.185 [MassServo] No Fault, FailCount= 1
2021-04-27T17:15:20.624Z,1619543720.624 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:15:20.624Z,1619543720.624 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-04-27T17:15:20.624Z,1619543720.624 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:15:20.680Z,1619543720.680 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:15:21.144Z,1619543721.144 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:15:21.880Z,1619543721.880 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:15:21.880Z,1619543721.880 [Aanderaa_O2] No Fault, FailCount= 1
2021-04-27T17:15:22.639Z,1619543722.639 [DAT](INFO): commRate: 800
2021-04-27T17:15:24.662Z,1619543724.662 [DAT](INFO): entering command mode
2021-04-27T17:15:25.062Z,1619543725.062 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:25.470Z,1619543725.470 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:25.874Z,1619543725.874 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:26.274Z,1619543726.274 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:26.682Z,1619543726.682 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:27.086Z,1619543727.086 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:27.485Z,1619543727.485 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:15:27.485Z,1619543727.485 [DVL_micro] Communications Fault, FailCount= 1
2021-04-27T17:15:27.485Z,1619543727.485 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:15:27.486Z,1619543727.486 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:27.555Z,1619543727.555 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:15:27.960Z,1619543727.960 [DVL_micro](INFO): Powering down
2021-04-27T17:15:27.961Z,1619543727.961 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:28.294Z,1619543728.294 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:28.701Z,1619543728.701 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:28.760Z,1619543728.760 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:15:28.760Z,1619543728.760 [DVL_micro] No Fault, FailCount= 1
2021-04-27T17:15:29.111Z,1619543729.111 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:29.525Z,1619543729.525 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:29.910Z,1619543729.910 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:29.929Z,1619543729.929 [SBIT](IMPORTANT): Beginning Startup BIT
2021-04-27T17:15:29.956Z,1619543729.956 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-27T17:15:30.314Z,1619543730.314 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:30.721Z,1619543730.721 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:31.122Z,1619543731.122 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:31.529Z,1619543731.529 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:31.937Z,1619543731.937 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:32.337Z,1619543732.337 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:32.338Z,1619543732.338 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:15:32.338Z,1619543732.338 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-04-27T17:15:32.338Z,1619543732.338 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:15:32.452Z,1619543732.452 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:15:32.738Z,1619543732.738 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:32.852Z,1619543732.852 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:15:33.146Z,1619543733.146 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:33.558Z,1619543733.558 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:33.761Z,1619543733.761 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:15:33.761Z,1619543733.761 [Aanderaa_O2] No Fault, FailCount= 2
2021-04-27T17:15:33.953Z,1619543733.953 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:34.361Z,1619543734.361 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:34.758Z,1619543734.758 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:35.162Z,1619543735.162 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:35.566Z,1619543735.566 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:35.974Z,1619543735.974 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:36.377Z,1619543736.377 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:36.778Z,1619543736.778 [NAL9602](INFO): Powering up NAL9602
2021-04-27T17:15:36.779Z,1619543736.779 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:37.198Z,1619543737.198 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:37.591Z,1619543737.591 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:37.990Z,1619543737.990 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:38.394Z,1619543738.394 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:38.801Z,1619543738.801 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:39.206Z,1619543739.206 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:39.610Z,1619543739.610 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:40.013Z,1619543740.013 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-27T17:15:40.014Z,1619543740.014 [DAT](FAULT): failed to enter command mode
2021-04-27T17:15:40.414Z,1619543740.414 [DAT](INFO): entering command mode
2021-04-27T17:15:40.819Z,1619543740.819 [DAT](INFO): setting verbose to 3
2021-04-27T17:15:40.891Z,1619543740.891 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006499
CHAN A1 (24V): -0.007722
CHAN A2 (12V): -0.004987
CHAN A3 (5V): -0.003825
CHAN B0 (3.3V): -0.000730
CHAN B1 (3.15aV): -0.001409
CHAN B2 (3.15bV): -0.001417
CHAN B3 (GND): 0.000093
OPEN: 0.006586
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-27T17:15:41.239Z,1619543741.239 [DAT](INFO): set verbose to 3
2021-04-27T17:15:41.239Z,1619543741.239 [DAT](INFO): setting DatVerbose to 27440
2021-04-27T17:15:41.627Z,1619543741.627 [DAT](INFO): set DatVerbose to 27440
2021-04-27T17:15:41.627Z,1619543741.627 [DAT](INFO): setting transmit power to 8
2021-04-27T17:15:42.031Z,1619543742.031 [DAT](INFO): set transmit power to 8
2021-04-27T17:15:42.031Z,1619543742.031 [DAT](INFO): setting local address to 5
2021-04-27T17:15:42.438Z,1619543742.438 [DAT](INFO): set local address to 5
2021-04-27T17:15:44.058Z,1619543744.058 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:15:44.058Z,1619543744.058 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-04-27T17:15:44.058Z,1619543744.058 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:15:44.112Z,1619543744.112 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:15:44.113Z,1619543744.113 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-04-27T17:15:44.576Z,1619543744.576 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:15:46.489Z,1619543746.489 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:15:46.489Z,1619543746.489 [DVL_micro] Communications Fault, FailCount= 2
2021-04-27T17:15:46.489Z,1619543746.489 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:15:46.608Z,1619543746.608 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:15:46.948Z,1619543746.948 [DVL_micro](INFO): Powering down
2021-04-27T17:15:47.694Z,1619543747.694 [NAL9602](INFO): NAL9602 initialized
2021-04-27T17:15:47.804Z,1619543747.804 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:15:47.804Z,1619543747.804 [DVL_micro] No Fault, FailCount= 2
2021-04-27T17:16:05.465Z,1619543765.465 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:16:05.465Z,1619543765.465 [DVL_micro] Communications Fault, FailCount= 3
2021-04-27T17:16:05.465Z,1619543765.465 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:16:05.487Z,1619543765.487 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:16:05.487Z,1619543765.487 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-04-27T17:16:05.944Z,1619543765.944 [DVL_micro](INFO): Powering down
2021-04-27T17:16:05.965Z,1619543765.964 [CBIT](INFO): Critical error at 20210427T171605
2021-04-27T17:16:12.339Z,1619543772.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=4091, MT Status=2, MTMSN=0
2021-04-27T17:16:12.339Z,1619543772.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-27T17:16:24.086Z,1619543784.086 [SBIT](IMPORTANT): SBIT PASSED
2021-04-27T17:16:24.086Z,1619543784.086 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-04-27T17:16:24.489Z,1619543784.489 [MissionManager](IMPORTANT): Started mission Startup
2021-04-27T17:16:24.490Z,1619543784.490 [Startup] Running Loop=1
2021-04-27T17:16:24.490Z,1619543784.490 [Startup](DEBUG): Aggregate::initialize Startup
2021-04-27T17:16:24.490Z,1619543784.490 [Startup:A.GoToSurface] Running Loop=1
2021-04-27T17:16:24.490Z,1619543784.490 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-27T17:16:24.490Z,1619543784.490 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-27T17:16:24.491Z,1619543784.491 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-27T17:16:24.491Z,1619543784.491 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-27T17:16:24.492Z,1619543784.492 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-27T17:16:24.492Z,1619543784.492 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-27T17:16:24.493Z,1619543784.493 [Startup:StartupSatComms] Running Loop=1
2021-04-27T17:16:24.493Z,1619543784.493 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-04-27T17:16:24.493Z,1619543784.493 [Startup:StartupSatComms:A] Running Loop=1
2021-04-27T17:16:24.891Z,1619543784.891 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-04-27T17:16:26.478Z,1619543786.478 [NAL9602](INFO): SBD MO Status=0, MOMSN=4091, MT Status=0, MTMSN=0
2021-04-27T17:16:26.479Z,1619543786.479 [NAL9602](INFO): No messages in MT queue
2021-04-27T17:16:26.480Z,1619543786.480 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:16:42.245Z,1619543802.245 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:16:57.991Z,1619543817.991 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:17:04.448Z,1619543824.448 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2021-04-27T17:17:12.944Z,1619543832.944 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:17:24.678Z,1619543844.678 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-27T17:16:24.5Z
2021-04-27T17:17:24.678Z,1619543844.678 [Startup:StartupSatComms:A] Stopped
2021-04-27T17:17:24.678Z,1619543844.678 [Startup:StartupSatComms:B] Running Loop=1
2021-04-27T17:17:25.070Z,1619543845.070 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-27T17:17:59.887Z,1619543879.887 [NAL9602](INFO): SBD MO Status=1, MOMSN=4092, MT Status=0, MTMSN=0
2021-04-27T17:17:59.943Z,1619543879.943 [NAL9602](INFO): Sent 150 bytes from file Logs/20210427T164801/Courier0000.lzma
2021-04-27T17:17:59.943Z,1619543879.943 [NAL9602](INFO): Packets left to send: 0
2021-04-27T17:18:09.493Z,1619543889.493 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-04-27T17:18:09.493Z,1619543889.493 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-27T17:18:09.508Z,1619543889.508 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-27T17:18:09.902Z,1619543889.902 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-27T17:18:09.902Z,1619543889.902 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-04-27T17:18:12.707Z,1619543892.707 [NAL9602](INFO): SBD MO Status=2, MOMSN=4093, MT Status=2, MTMSN=0
2021-04-27T17:18:12.707Z,1619543892.707 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-27T17:18:14.355Z,1619543894.355 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:18:14.355Z,1619543894.355 [Aanderaa_O2] No Fault, FailCount= 3
2021-04-27T17:18:26.162Z,1619543906.162 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:18:26.162Z,1619543906.162 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-04-27T17:18:26.162Z,1619543906.162 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:18:26.169Z,1619543906.169 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-27T17:17:24.7Z
2021-04-27T17:18:26.169Z,1619543906.169 [Startup:StartupSatComms:B] Stopped
2021-04-27T17:18:26.169Z,1619543906.169 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-04-27T17:18:26.169Z,1619543906.169 [Startup:StartupSatComms] Stopped
2021-04-27T17:18:26.169Z,1619543906.169 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-04-27T17:18:26.170Z,1619543906.170 [Startup](INFO): Completed Startup
2021-04-27T17:18:26.170Z,1619543906.170 [MissionManager](INFO): Startup is completed.
2021-04-27T17:18:26.170Z,1619543906.170 [MissionManager](INFO): Uninitializing Mission Startup
2021-04-27T17:18:26.170Z,1619543906.170 [Startup] Stopped
2021-04-27T17:18:26.171Z,1619543906.171 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-04-27T17:18:26.171Z,1619543906.171 [Startup:A.GoToSurface] Stopped
2021-04-27T17:18:26.171Z,1619543906.171 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-27T17:18:26.213Z,1619543906.213 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:18:26.388Z,1619543906.388 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:18:26.402Z,1619543906.402 [MissionManager](IMPORTANT): Started mission Default
2021-04-27T17:18:26.402Z,1619543906.402 [Default] Running Loop=1
2021-04-27T17:18:26.402Z,1619543906.402 [Default](DEBUG): Aggregate::initialize Default
2021-04-27T17:18:26.402Z,1619543906.402 [Default:B.GoToSurface] Running Loop=1
2021-04-27T17:18:26.402Z,1619543906.402 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-27T17:18:26.402Z,1619543906.402 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-27T17:18:26.403Z,1619543906.403 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-27T17:18:26.403Z,1619543906.403 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-27T17:18:26.403Z,1619543906.403 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-27T17:18:26.404Z,1619543906.404 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-27T17:18:26.404Z,1619543906.404 [Default:A.Wait] Running Loop=1
2021-04-27T17:18:26.404Z,1619543906.404 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-27T17:18:27.089Z,1619543907.089 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:18:27.089Z,1619543907.089 [Aanderaa_O2] No Fault, FailCount= 1
2021-04-27T17:18:33.462Z,1619543913.462 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004452
2021-04-27T17:18:37.704Z,1619543917.704 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:18:37.704Z,1619543917.704 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-04-27T17:18:37.704Z,1619543917.704 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:18:37.721Z,1619543917.721 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:18:38.239Z,1619543918.239 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:18:38.928Z,1619543918.928 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:18:38.928Z,1619543918.928 [Aanderaa_O2] No Fault, FailCount= 2
2021-04-27T17:18:39.743Z,1619543919.743 [Default:A.Wait](INFO): Done Waiting.
2021-04-27T17:18:39.743Z,1619543919.743 [Default:A.Wait] Stopped
2021-04-27T17:18:39.743Z,1619543919.743 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-27T17:18:40.129Z,1619543920.129 [Default:CheckIn] Running Loop=1
2021-04-27T17:18:40.129Z,1619543920.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-27T17:18:40.129Z,1619543920.129 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-27T17:18:40.553Z,1619543920.553 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-27T17:18:49.484Z,1619543929.484 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:18:49.484Z,1619543929.484 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-04-27T17:18:49.484Z,1619543929.484 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:18:49.556Z,1619543929.556 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:18:49.557Z,1619543929.557 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-04-27T17:18:50.015Z,1619543930.015 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:19:05.644Z,1619543945.644 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:19:05.645Z,1619543945.645 [DVL_micro] No Fault, FailCount= 3
2021-04-27T17:19:23.399Z,1619543963.399 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:19:23.400Z,1619543963.400 [DVL_micro] Communications Fault, FailCount= 1
2021-04-27T17:19:23.400Z,1619543963.400 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:19:23.456Z,1619543963.456 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:19:23.870Z,1619543963.870 [DVL_micro](INFO): Powering down
2021-04-27T17:19:24.703Z,1619543964.703 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:19:24.703Z,1619543964.703 [DVL_micro] No Fault, FailCount= 1
2021-04-27T17:19:42.395Z,1619543982.395 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:19:42.395Z,1619543982.395 [DVL_micro] Communications Fault, FailCount= 2
2021-04-27T17:19:42.396Z,1619543982.396 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:19:42.414Z,1619543982.414 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:19:42.859Z,1619543982.859 [DVL_micro](INFO): Powering down
2021-04-27T17:19:43.647Z,1619543983.647 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:19:43.647Z,1619543983.647 [DVL_micro] No Fault, FailCount= 2
2021-04-27T17:20:01.379Z,1619544001.379 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:20:01.379Z,1619544001.379 [DVL_micro] Communications Fault, FailCount= 3
2021-04-27T17:20:01.379Z,1619544001.379 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:20:01.439Z,1619544001.439 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:20:01.440Z,1619544001.440 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-04-27T17:20:01.858Z,1619544001.858 [DVL_micro](INFO): Powering down
2021-04-27T17:20:01.900Z,1619544001.900 [CBIT](INFO): Critical error at 20210427T172001
2021-04-27T17:21:01.978Z,1619544061.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172100.00,A,3648.16393,N,12147.29370,W,0.544,337.39,270421,,,A*7A
2021-04-27T17:21:01.981Z,1619544061.981 [NAL9602](INFO): GPS fix at 20210427T172100: (36.802732, -121.788228)
2021-04-27T17:21:01.992Z,1619544061.992 [Default:CheckIn:Read_GPS] Stopped
2021-04-27T17:21:01.992Z,1619544061.992 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-27T17:21:02.422Z,1619544062.422 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-27T17:21:12.986Z,1619544072.986 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210427T164801/Courier0004.lzma
2021-04-27T17:21:13.988Z,1619544073.988 [DataOverHttps](INFO): Moved sent file to Logs/20210427T164801/Courier0004.lzma.bak
2021-04-27T17:21:13.988Z,1619544073.988 [DataOverHttps](INFO): SBD MOMSN=15605309
2021-04-27T17:21:19.487Z,1619544079.487 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:21:19.487Z,1619544079.487 [Aanderaa_O2] No Fault, FailCount= 3
2021-04-27T17:21:30.045Z,1619544090.045 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:21:30.045Z,1619544090.045 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-04-27T17:21:30.045Z,1619544090.045 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:21:30.193Z,1619544090.193 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:21:30.566Z,1619544090.566 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:21:31.299Z,1619544091.299 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:21:31.299Z,1619544091.299 [Aanderaa_O2] No Fault, FailCount= 1
2021-04-27T17:21:34.328Z,1619544094.328 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20210427T165308/Courier0000.lzma
2021-04-27T17:21:34.485Z,1619544094.485 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-27T17:21:35.328Z,1619544095.328 [DataOverHttps](INFO): Moved sent file to Logs/20210427T165308/Courier0000.lzma.bak
2021-04-27T17:21:35.328Z,1619544095.328 [DataOverHttps](INFO): SBD MOMSN=15605311
2021-04-27T17:21:41.761Z,1619544101.761 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:21:41.761Z,1619544101.761 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-04-27T17:21:41.761Z,1619544101.761 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:21:41.827Z,1619544101.827 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:21:42.294Z,1619544102.294 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:21:43.015Z,1619544103.015 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:21:43.015Z,1619544103.015 [Aanderaa_O2] No Fault, FailCount= 2
2021-04-27T17:21:53.559Z,1619544113.559 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:21:53.559Z,1619544113.559 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-04-27T17:21:53.559Z,1619544113.559 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:21:53.577Z,1619544113.577 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:21:53.577Z,1619544113.577 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-04-27T17:21:54.010Z,1619544114.010 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210427T165308/Courier0004.lzma
2021-04-27T17:21:54.018Z,1619544114.018 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:21:55.012Z,1619544115.012 [DataOverHttps](INFO): Moved sent file to Logs/20210427T165308/Courier0004.lzma.bak
2021-04-27T17:21:55.012Z,1619544115.012 [DataOverHttps](INFO): SBD MOMSN=15605316
2021-04-27T17:22:15.328Z,1619544135.328 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20210427T165933/Courier0000.lzma
2021-04-27T17:22:16.336Z,1619544136.336 [DataOverHttps](INFO): Moved sent file to Logs/20210427T165933/Courier0000.lzma.bak
2021-04-27T17:22:16.336Z,1619544136.336 [DataOverHttps](INFO): SBD MOMSN=15605318
2021-04-27T17:22:57.749Z,1619544177.749 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210427T165933/Courier0004.lzma
2021-04-27T17:22:58.752Z,1619544178.752 [DataOverHttps](INFO): Moved sent file to Logs/20210427T165933/Courier0004.lzma.bak
2021-04-27T17:22:58.752Z,1619544178.752 [DataOverHttps](INFO): SBD MOMSN=15605322
2021-04-27T17:23:01.403Z,1619544181.403 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:23:01.403Z,1619544181.403 [DVL_micro] No Fault, FailCount= 3
2021-04-27T17:23:17.187Z,1619544197.187 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20210427T170335/Courier0000.lzma
2021-04-27T17:23:18.184Z,1619544198.184 [DataOverHttps](INFO): Moved sent file to Logs/20210427T170335/Courier0000.lzma.bak
2021-04-27T17:23:18.184Z,1619544198.184 [DataOverHttps](INFO): SBD MOMSN=15605324
2021-04-27T17:23:19.132Z,1619544199.132 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:23:19.132Z,1619544199.132 [DVL_micro] Communications Fault, FailCount= 1
2021-04-27T17:23:19.132Z,1619544199.132 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:23:19.151Z,1619544199.151 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:23:19.595Z,1619544199.595 [DVL_micro](INFO): Powering down
2021-04-27T17:23:20.393Z,1619544200.393 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:23:20.393Z,1619544200.393 [DVL_micro] No Fault, FailCount= 1
2021-04-27T17:23:38.109Z,1619544218.109 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:23:38.109Z,1619544218.109 [DVL_micro] Communications Fault, FailCount= 2
2021-04-27T17:23:38.109Z,1619544218.109 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:23:38.166Z,1619544218.166 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:23:38.437Z,1619544218.437 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210427T170335/Courier0004.lzma
2021-04-27T17:23:38.582Z,1619544218.582 [DVL_micro](INFO): Powering down
2021-04-27T17:23:39.354Z,1619544219.354 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:23:39.355Z,1619544219.355 [DVL_micro] No Fault, FailCount= 2
2021-04-27T17:23:39.440Z,1619544219.440 [DataOverHttps](INFO): Moved sent file to Logs/20210427T170335/Courier0004.lzma.bak
2021-04-27T17:23:39.440Z,1619544219.440 [DataOverHttps](INFO): SBD MOMSN=15605329
2021-04-27T17:23:57.105Z,1619544237.105 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:23:57.105Z,1619544237.105 [DVL_micro] Communications Fault, FailCount= 3
2021-04-27T17:23:57.105Z,1619544237.105 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:23:57.124Z,1619544237.124 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:23:57.124Z,1619544237.124 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-04-27T17:23:57.567Z,1619544237.567 [DVL_micro](INFO): Powering down
2021-04-27T17:23:57.589Z,1619544237.589 [CBIT](INFO): Critical error at 20210427T172357
2021-04-27T17:23:57.816Z,1619544237.816 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20210427T170840/Courier0000.lzma
2021-04-27T17:23:58.812Z,1619544238.812 [DataOverHttps](INFO): Moved sent file to Logs/20210427T170840/Courier0000.lzma.bak
2021-04-27T17:23:58.812Z,1619544238.812 [DataOverHttps](INFO): SBD MOMSN=15605331
2021-04-27T17:24:08.008Z,1619544248.008 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-04-27T17:24:14.473Z,1619544254.473 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:24:17.246Z,1619544257.246 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210427T170840/Courier0004.lzma
2021-04-27T17:24:20.435Z,1619544260.435 [DataOverHttps](INFO): Moved sent file to Logs/20210427T170840/Courier0004.lzma.bak
2021-04-27T17:24:20.435Z,1619544260.435 [DataOverHttps](INFO): SBD MOMSN=15605335
2021-04-27T17:24:23.809Z,1619544263.809 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:24:23.809Z,1619544263.809 [Aanderaa_O2] No Fault, FailCount= 3
2021-04-27T17:24:30.292Z,1619544270.292 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:24:34.288Z,1619544274.288 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:24:34.289Z,1619544274.289 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-04-27T17:24:34.289Z,1619544274.289 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:24:34.424Z,1619544274.424 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:24:34.839Z,1619544274.839 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:24:35.611Z,1619544275.611 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:24:35.611Z,1619544275.611 [Aanderaa_O2] No Fault, FailCount= 1
2021-04-27T17:24:40.878Z,1619544280.878 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20210427T171458/Courier0000.lzma
2021-04-27T17:24:41.880Z,1619544281.880 [DataOverHttps](INFO): Moved sent file to Logs/20210427T171458/Courier0000.lzma.bak
2021-04-27T17:24:41.880Z,1619544281.880 [DataOverHttps](INFO): SBD MOMSN=15605337
2021-04-27T17:24:45.677Z,1619544285.677 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:24:46.095Z,1619544286.095 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:24:46.095Z,1619544286.095 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-04-27T17:24:46.095Z,1619544286.095 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:24:46.224Z,1619544286.224 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:24:46.643Z,1619544286.643 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:24:47.404Z,1619544287.404 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:24:47.404Z,1619544287.404 [Aanderaa_O2] No Fault, FailCount= 2
2021-04-27T17:24:57.891Z,1619544297.891 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:24:57.892Z,1619544297.892 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-04-27T17:24:57.892Z,1619544297.892 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:24:57.932Z,1619544297.932 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:24:57.932Z,1619544297.932 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-04-27T17:24:58.395Z,1619544298.395 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:25:00.297Z,1619544300.297 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:25:05.247Z,1619544305.247 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20210427T171458/Courier0004.lzma
2021-04-27T17:25:06.248Z,1619544306.248 [DataOverHttps](INFO): Moved sent file to Logs/20210427T171458/Courier0004.lzma.bak
2021-04-27T17:25:06.248Z,1619544306.248 [DataOverHttps](INFO): SBD MOMSN=15605342
2021-04-27T17:25:16.040Z,1619544316.040 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:25:27.461Z,1619544327.461 [DataOverHttps](INFO): Sending 806 bytes from file Logs/20210427T144302/Express0053.lzma
2021-04-27T17:25:28.460Z,1619544328.460 [DataOverHttps](INFO): Moved sent file to Logs/20210427T144302/Express0053.lzma.bak
2021-04-27T17:25:28.460Z,1619544328.460 [DataOverHttps](INFO): SBD MOMSN=15605346
2021-04-27T17:25:31.806Z,1619544331.806 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:25:47.552Z,1619544347.552 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:25:50.377Z,1619544350.377 [DataOverHttps](INFO): Sending 1015 bytes from file Logs/20210427T164801/Express0001.lzma
2021-04-27T17:25:51.379Z,1619544351.379 [DataOverHttps](INFO): Moved sent file to Logs/20210427T164801/Express0001.lzma.bak
2021-04-27T17:25:51.379Z,1619544351.379 [DataOverHttps](INFO): SBD MOMSN=15605377
2021-04-27T17:26:03.312Z,1619544363.312 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:26:11.842Z,1619544371.842 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210427T164801/Express0005.lzma
2021-04-27T17:26:12.840Z,1619544372.840 [DataOverHttps](INFO): Moved sent file to Logs/20210427T164801/Express0005.lzma.bak
2021-04-27T17:26:12.840Z,1619544372.840 [DataOverHttps](INFO): SBD MOMSN=15605404
2021-04-27T17:26:19.083Z,1619544379.083 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-04-27T17:26:42.950Z,1619544402.950 [DataOverHttps](INFO): Sending 992 bytes from file Logs/20210427T165308/Express0001.lzma
2021-04-27T17:26:43.948Z,1619544403.948 [DataOverHttps](INFO): Moved sent file to Logs/20210427T165308/Express0001.lzma.bak
2021-04-27T17:26:43.948Z,1619544403.948 [DataOverHttps](INFO): SBD MOMSN=15605406
2021-04-27T17:26:57.495Z,1619544417.495 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:26:57.495Z,1619544417.495 [DVL_micro] No Fault, FailCount= 3
2021-04-27T17:27:07.170Z,1619544427.170 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210427T165308/Express0005.lzma
2021-04-27T17:27:08.172Z,1619544428.172 [DataOverHttps](INFO): Moved sent file to Logs/20210427T165308/Express0005.lzma.bak
2021-04-27T17:27:08.172Z,1619544428.172 [DataOverHttps](INFO): SBD MOMSN=15605434
2021-04-27T17:27:15.231Z,1619544435.231 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:27:15.231Z,1619544435.231 [DVL_micro] Communications Fault, FailCount= 1
2021-04-27T17:27:15.231Z,1619544435.231 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:27:15.283Z,1619544435.283 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:27:15.707Z,1619544435.707 [DVL_micro](INFO): Powering down
2021-04-27T17:27:16.470Z,1619544436.470 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:27:16.470Z,1619544436.470 [DVL_micro] No Fault, FailCount= 1
2021-04-27T17:27:21.788Z,1619544441.788 [CommandLine](IMPORTANT): got command failComponent
2021-04-27T17:27:21.788Z,1619544441.788 [CommandLine](IMPORTANT): Failed components:
2021-04-27T17:27:21.788Z,1619544441.788 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2021-04-27T17:27:28.211Z,1619544448.211 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-04-27T17:27:28.211Z,1619544448.211 [Aanderaa_O2] No Fault, FailCount= 3
2021-04-27T17:27:29.713Z,1619544449.713 [DataOverHttps](INFO): Sending 871 bytes from file Logs/20210427T165933/Express0001.lzma
2021-04-27T17:27:29.859Z,1619544449.859 [CommandLine](IMPORTANT): got command restart system
2021-04-27T17:27:30.712Z,1619544450.712 [DataOverHttps](INFO): Moved sent file to Logs/20210427T165933/Express0001.lzma.bak
2021-04-27T17:27:30.712Z,1619544450.712 [DataOverHttps](INFO): SBD MOMSN=15605436
2021-04-27T17:27:31.914Z,1619544451.914 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:31.915Z,1619544451.915 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:31.946Z,1619544451.946 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-27T17:27:31.946Z,1619544451.946 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:31.947Z,1619544451.947 [CommandLine](INFO): Join timeout helper Thread ID is 1014
2021-04-27T17:27:31.948Z,1619544451.948 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-27T17:27:31.948Z,1619544451.948 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:31.948Z,1619544451.948 [NavChartDb](INFO): Join timeout helper Thread ID is 1015
2021-04-27T17:27:32.086Z,1619544452.086 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:32.087Z,1619544452.087 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:32.094Z,1619544452.094 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-04-27T17:27:32.094Z,1619544452.094 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:32.095Z,1619544452.095 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1016
2021-04-27T17:27:32.363Z,1619544452.363 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:32.363Z,1619544452.363 [WetLabsBB2FL](INFO): Powering down
2021-04-27T17:27:32.364Z,1619544452.364 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:32.378Z,1619544452.378 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-04-27T17:27:32.378Z,1619544452.378 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:32.379Z,1619544452.379 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1017
2021-04-27T17:27:32.778Z,1619544452.778 [CTD_Seabird](INFO): Powering down
2021-04-27T17:27:32.790Z,1619544452.790 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:32.790Z,1619544452.790 [CTD_Seabird](INFO): Powering down
2021-04-27T17:27:32.802Z,1619544452.802 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:32.818Z,1619544452.818 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-27T17:27:32.818Z,1619544452.818 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:32.819Z,1619544452.819 [Radio_Surface](INFO): Join timeout helper Thread ID is 1018
2021-04-27T17:27:33.122Z,1619544453.122 [Radio_Surface](INFO): Powering down
2021-04-27T17:27:33.123Z,1619544453.123 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:33.124Z,1619544453.124 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:33.131Z,1619544453.131 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-27T17:27:33.131Z,1619544453.131 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:33.132Z,1619544453.132 [Onboard](INFO): Join timeout helper Thread ID is 1019
2021-04-27T17:27:34.220Z,1619544454.220 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-04-27T17:27:34.220Z,1619544454.220 [DVL_micro] Communications Fault, FailCount= 2
2021-04-27T17:27:34.220Z,1619544454.220 [DVL_micro](ERROR): Communications Fault
2021-04-27T17:27:34.317Z,1619544454.317 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-04-27T17:27:34.627Z,1619544454.627 [DVL_micro](INFO): Powering down
2021-04-27T17:27:35.564Z,1619544455.564 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-04-27T17:27:35.564Z,1619544455.564 [DVL_micro] No Fault, FailCount= 2
2021-04-27T17:27:37.054Z,1619544457.054 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:37.055Z,1619544457.055 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:37.059Z,1619544457.059 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-27T17:27:37.059Z,1619544457.059 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:37.060Z,1619544457.060 [DataOverHttps](INFO): Join timeout helper Thread ID is 1020
2021-04-27T17:27:38.795Z,1619544458.795 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-04-27T17:27:38.795Z,1619544458.795 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-04-27T17:27:38.795Z,1619544458.795 [Aanderaa_O2](ERROR): Communications Fault
2021-04-27T17:27:38.838Z,1619544458.838 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-04-27T17:27:39.202Z,1619544459.202 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-04-27T17:27:39.203Z,1619544459.203 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:39.203Z,1619544459.203 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.215Z,1619544459.215 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-27T17:27:39.215Z,1619544459.215 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.215Z,1619544459.215 [logger](INFO): Join timeout helper Thread ID is 1021
2021-04-27T17:27:39.216Z,1619544459.216 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:27:39.223Z,1619544459.223 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:39.223Z,1619544459.223 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.239Z,1619544459.239 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-27T17:27:39.239Z,1619544459.239 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.239Z,1619544459.239 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-27T17:27:39.239Z,1619544459.239 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.240Z,1619544459.240 [controlThread](INFO): Join timeout helper Thread ID is 1022
2021-04-27T17:27:39.450Z,1619544459.450 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-27T17:27:39.451Z,1619544459.451 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-27T17:27:39.451Z,1619544459.451 [AHRS_M2](INFO): Powering down
2021-04-27T17:27:39.594Z,1619544459.594 [DVL_micro](INFO): Powering down
2021-04-27T17:27:39.595Z,1619544459.595 [NAL9602](INFO): Powering down
2021-04-27T17:27:39.596Z,1619544459.596 [DAT](INFO): Powering down
2021-04-27T17:27:39.715Z,1619544459.715 [Aanderaa_O2](INFO): Powering down
2021-04-27T17:27:39.718Z,1619544459.718 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-27T17:27:39.719Z,1619544459.719 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-27T17:27:39.719Z,1619544459.719 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-27T17:27:39.720Z,1619544459.720 [MissionManager](INFO): Uninitializing Mission Default
2021-04-27T17:27:39.720Z,1619544459.720 [Default] Stopped
2021-04-27T17:27:39.720Z,1619544459.720 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-27T17:27:39.720Z,1619544459.720 [Default:B.GoToSurface] Stopped
2021-04-27T17:27:39.720Z,1619544459.720 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-27T17:27:39.720Z,1619544459.720 [Default:CheckIn] Stopped
2021-04-27T17:27:39.720Z,1619544459.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-27T17:27:39.721Z,1619544459.721 [Default:CheckIn:Read_Iridium] Stopped
2021-04-27T17:27:39.723Z,1619544459.723 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-27T17:27:39.723Z,1619544459.723 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-27T17:27:39.724Z,1619544459.724 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-27T17:27:39.724Z,1619544459.724 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-27T17:27:39.724Z,1619544459.724 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-27T17:27:39.724Z,1619544459.724 [BuoyancyServo](INFO): Powering down
2021-04-27T17:27:39.738Z,1619544459.738 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-27T17:27:39.739Z,1619544459.739 [ElevatorServo](INFO): Powering down
2021-04-27T17:27:39.739Z,1619544459.739 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-27T17:27:39.739Z,1619544459.739 [MassServo](INFO): Powering down
2021-04-27T17:27:39.740Z,1619544459.740 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-27T17:27:39.740Z,1619544459.740 [RudderServo](INFO): Powering down
2021-04-27T17:27:39.741Z,1619544459.741 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-27T17:27:39.741Z,1619544459.741 [ThrusterServo](INFO): Powering down
2021-04-27T17:27:39.742Z,1619544459.742 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-27T17:27:39.742Z,1619544459.742 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-27T17:27:39.743Z,1619544459.743 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-27T17:27:39.743Z,1619544459.743 [CBIT](DEBUG): Powering off loads.
2021-04-27T17:27:39.754Z,1619544459.754 [CBIT](DEBUG): Disabling WDT.
2021-04-27T17:27:39.766Z,1619544459.766 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-27T17:27:39.767Z,1619544459.767 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.856Z,1619544459.856 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.858Z,1619544459.858 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.865Z,1619544459.865 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.905Z,1619544459.905 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.911Z,1619544459.911 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:39.946Z,1619544459.946 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-27T17:27:40.018Z,1619544460.018 [logger ThreadHandler](INFO): Thread cancelled.