2020-01-07T23:45:02.727Z,1578440702.727 [Supervisor](DEBUG): Initializing supervisor.
2020-01-07T23:45:02.731Z,1578440702.731 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-07T23:45:02.731Z,1578440702.731 [SyncHandler](INFO): Protected caller Thread ID is 7597
2020-01-07T23:45:02.731Z,1578440702.731 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-07T23:45:02.732Z,1578440702.732 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-07T23:45:02.733Z,1578440702.733 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7598
2020-01-07T23:45:02.748Z,1578440702.748 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-07T23:45:02.760Z,1578440702.760 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-07T23:45:02.761Z,1578440702.761 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-07T23:45:02.762Z,1578440702.762 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7599
2020-01-07T23:45:02.762Z,1578440702.762 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-07T23:45:02.763Z,1578440702.763 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-07T23:45:02.764Z,1578440702.764 [logger ThreadHandler](INFO): Protected caller Thread ID is 7600
2020-01-07T23:45:02.766Z,1578440702.766 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-07T23:45:02.766Z,1578440702.766 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-07T23:45:02.768Z,1578440702.768 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-07T23:45:03.035Z,1578440703.035 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-07T23:45:03.036Z,1578440703.036 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-07T23:45:03.118Z,1578440703.118 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-07T23:45:03.558Z,1578440703.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-07T23:45:03.559Z,1578440703.559 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-07T23:45:03.884Z,1578440703.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-07T23:45:03.884Z,1578440703.884 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-07T23:45:03.982Z,1578440703.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-07T23:45:03.983Z,1578440703.983 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-07T23:45:04.280Z,1578440704.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-07T23:45:04.280Z,1578440704.280 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-07T23:45:04.473Z,1578440704.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-07T23:45:04.474Z,1578440704.474 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-07T23:45:04.937Z,1578440704.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-07T23:45:04.938Z,1578440704.938 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-07T23:45:05.042Z,1578440705.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-07T23:45:05.043Z,1578440705.043 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-07T23:45:05.147Z,1578440705.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-07T23:45:05.148Z,1578440705.148 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-07T23:45:05.834Z,1578440705.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-07T23:45:05.835Z,1578440705.835 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-07T23:45:06.291Z,1578440706.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-07T23:45:06.292Z,1578440706.292 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-07T23:45:06.701Z,1578440706.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-07T23:45:06.702Z,1578440706.702 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-07T23:45:06.847Z,1578440706.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-07T23:45:06.848Z,1578440706.848 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-07T23:45:06.996Z,1578440706.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-07T23:45:06.998Z,1578440706.998 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2020-01-07T23:45:06.999Z,1578440706.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2020-01-07T23:45:07.091Z,1578440707.091 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2020-01-07T23:45:07.178Z,1578440707.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2020-01-07T23:45:07.280Z,1578440707.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2020-01-07T23:45:07.364Z,1578440707.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2020-01-07T23:45:07.461Z,1578440707.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2020-01-07T23:45:07.563Z,1578440707.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2020-01-07T23:45:07.766Z,1578440707.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2020-01-07T23:45:07.845Z,1578440707.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2020-01-07T23:45:08.007Z,1578440708.007 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2020-01-07T23:45:08.153Z,1578440708.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2020-01-07T23:45:08.270Z,1578440708.270 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2020-01-07T23:45:08.499Z,1578440708.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-07T23:45:08.501Z,1578440708.501 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2020-01-07T23:45:08.501Z,1578440708.501 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-07T23:45:08.513Z,1578440708.513 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-07T23:45:08.562Z,1578440708.562 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-07T23:45:08.679Z,1578440708.679 [VerticalControl] Loaded
2020-01-07T23:45:08.679Z,1578440708.679 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-07T23:45:08.680Z,1578440708.680 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-07T23:45:08.752Z,1578440708.752 [HorizontalControl] Loaded
2020-01-07T23:45:08.752Z,1578440708.752 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-07T23:45:08.753Z,1578440708.753 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-07T23:45:08.759Z,1578440708.759 [SpeedControl] Loaded
2020-01-07T23:45:08.759Z,1578440708.759 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-07T23:45:08.760Z,1578440708.760 [LoopControl](DEBUG): Construct LoopControl.
2020-01-07T23:45:08.760Z,1578440708.760 [LoopControl] Loaded
2020-01-07T23:45:08.761Z,1578440708.761 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-07T23:45:08.761Z,1578440708.761 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-07T23:45:08.762Z,1578440708.762 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-07T23:45:08.775Z,1578440708.775 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-07T23:45:08.776Z,1578440708.776 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-07T23:45:08.878Z,1578440708.878 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-07T23:45:08.879Z,1578440708.879 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-07T23:45:08.988Z,1578440708.988 [BuoyancyServo] Loaded
2020-01-07T23:45:08.988Z,1578440708.988 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-07T23:45:08.000Z,1578440709.000 [ElevatorServo] Loaded
2020-01-07T23:45:08.000Z,1578440709.000 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-07T23:45:09.011Z,1578440709.011 [MassServo] Loaded
2020-01-07T23:45:09.011Z,1578440709.011 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-07T23:45:09.023Z,1578440709.023 [RudderServo] Loaded
2020-01-07T23:45:09.023Z,1578440709.023 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-07T23:45:09.034Z,1578440709.034 [ThrusterServo] Loaded
2020-01-07T23:45:09.034Z,1578440709.034 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-07T23:45:09.035Z,1578440709.035 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-07T23:45:09.035Z,1578440709.035 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-07T23:45:09.145Z,1578440709.145 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-07T23:45:09.146Z,1578440709.146 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-07T23:45:09.167Z,1578440709.167 [NavChart] Loaded
2020-01-07T23:45:09.167Z,1578440709.167 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-07T23:45:09.171Z,1578440709.171 [UniversalFixResidualReporter] Loaded
2020-01-07T23:45:09.171Z,1578440709.171 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-07T23:45:09.172Z,1578440709.172 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-07T23:45:09.172Z,1578440709.172 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-07T23:45:09.248Z,1578440709.248 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-07T23:45:09.248Z,1578440709.248 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-07T23:45:09.643Z,1578440709.643 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-07T23:45:09.648Z,1578440709.648 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-07T23:45:09.651Z,1578440709.651 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-07T23:45:09.656Z,1578440709.656 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-07T23:45:09.657Z,1578440709.657 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-07T23:45:09.662Z,1578440709.662 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-07T23:45:09.662Z,1578440709.662 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-07T23:45:09.667Z,1578440709.667 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-07T23:45:09.848Z,1578440709.848 [AHRS_M2] Loaded
2020-01-07T23:45:09.849Z,1578440709.849 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-07T23:45:10.159Z,1578440710.159 [DataOverHttps] Loaded
2020-01-07T23:45:10.159Z,1578440710.159 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-07T23:45:10.160Z,1578440710.160 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408644E0
2020-01-07T23:45:10.160Z,1578440710.160 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7683
2020-01-07T23:45:10.174Z,1578440710.174 [Depth_Keller] Loaded
2020-01-07T23:45:10.174Z,1578440710.174 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-07T23:45:10.272Z,1578440710.272 [NAL9602] Loaded
2020-01-07T23:45:10.273Z,1578440710.273 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-07T23:45:10.289Z,1578440710.289 [Onboard] Loaded
2020-01-07T23:45:10.289Z,1578440710.289 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-07T23:45:10.292Z,1578440710.292 [Radio_Surface] Loaded
2020-01-07T23:45:10.293Z,1578440710.293 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-07T23:45:10.294Z,1578440710.294 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408944E0
2020-01-07T23:45:10.294Z,1578440710.294 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7684
2020-01-07T23:45:12.194Z,1578440712.194 [BPC1] Loaded
2020-01-07T23:45:12.194Z,1578440712.194 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-07T23:45:12.195Z,1578440712.195 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-07T23:45:12.195Z,1578440712.195 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-07T23:45:12.247Z,1578440712.247 [DepthRateCalculator] Loaded
2020-01-07T23:45:12.247Z,1578440712.247 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-07T23:45:12.252Z,1578440712.252 [PitchRateCalculator] Loaded
2020-01-07T23:45:12.253Z,1578440712.253 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-07T23:45:12.265Z,1578440712.265 [SpeedCalculator] Loaded
2020-01-07T23:45:12.265Z,1578440712.265 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-07T23:45:12.287Z,1578440712.287 [TempGradientCalculator] Loaded
2020-01-07T23:45:12.287Z,1578440712.287 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-07T23:45:12.292Z,1578440712.292 [YawRateCalculator] Loaded
2020-01-07T23:45:12.293Z,1578440712.293 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-07T23:45:12.430Z,1578440712.430 [ElevatorOffsetCalculator] Loaded
2020-01-07T23:45:12.430Z,1578440712.430 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-07T23:45:12.430Z,1578440712.430 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-07T23:45:12.431Z,1578440712.431 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-07T23:45:12.635Z,1578440712.635 [CANONSampler] Loaded
2020-01-07T23:45:12.636Z,1578440712.636 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-01-07T23:45:12.645Z,1578440712.645 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-01-07T23:45:12.650Z,1578440712.650 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-01-07T23:45:12.650Z,1578440712.650 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-01-07T23:45:12.655Z,1578440712.655 [CTD_Seabird](INFO): created writer for : depth
2020-01-07T23:45:12.656Z,1578440712.656 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-01-07T23:45:12.661Z,1578440712.661 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-01-07T23:45:12.662Z,1578440712.662 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-01-07T23:45:12.667Z,1578440712.667 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-01-07T23:45:12.667Z,1578440712.667 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-01-07T23:45:12.672Z,1578440712.672 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-01-07T23:45:12.673Z,1578440712.673 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-01-07T23:45:12.679Z,1578440712.679 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-01-07T23:45:12.679Z,1578440712.679 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-01-07T23:45:12.684Z,1578440712.684 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-01-07T23:45:12.685Z,1578440712.685 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-01-07T23:45:12.691Z,1578440712.691 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-01-07T23:45:12.691Z,1578440712.691 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-01-07T23:45:12.692Z,1578440712.692 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-01-07T23:45:12.725Z,1578440712.725 [CTD_Seabird] Loaded
2020-01-07T23:45:12.725Z,1578440712.725 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-01-07T23:45:12.726Z,1578440712.726 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409964E0
2020-01-07T23:45:12.726Z,1578440712.726 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7685
2020-01-07T23:45:12.741Z,1578440712.741 [PAR_Licor] Loaded
2020-01-07T23:45:12.741Z,1578440712.741 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-07T23:45:12.747Z,1578440712.747 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-01-07T23:45:12.748Z,1578440712.748 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-01-07T23:45:12.752Z,1578440712.752 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-01-07T23:45:12.752Z,1578440712.752 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-01-07T23:45:12.757Z,1578440712.757 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-01-07T23:45:12.757Z,1578440712.757 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-01-07T23:45:12.761Z,1578440712.761 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-01-07T23:45:12.762Z,1578440712.762 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-01-07T23:45:12.766Z,1578440712.766 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-01-07T23:45:12.766Z,1578440712.766 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-01-07T23:45:12.771Z,1578440712.771 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-01-07T23:45:12.771Z,1578440712.771 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-01-07T23:45:12.775Z,1578440712.775 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-01-07T23:45:12.776Z,1578440712.776 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-01-07T23:45:12.780Z,1578440712.780 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.785Z,1578440712.785 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.785Z,1578440712.785 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.785Z,1578440712.785 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.790Z,1578440712.790 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.790Z,1578440712.790 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.794Z,1578440712.794 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.795Z,1578440712.795 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-01-07T23:45:12.799Z,1578440712.799 [WetLabsBB2FL] Loaded
2020-01-07T23:45:12.799Z,1578440712.799 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-01-07T23:45:12.802Z,1578440712.802 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409C64E0
2020-01-07T23:45:12.802Z,1578440712.802 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7686
2020-01-07T23:45:12.803Z,1578440712.803 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-07T23:45:12.803Z,1578440712.803 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-07T23:45:12.836Z,1578440712.836 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-07T23:45:12.837Z,1578440712.837 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-07T23:45:13.142Z,1578440713.142 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-07T23:45:13.143Z,1578440713.143 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-07T23:45:13.309Z,1578440713.309 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-07T23:45:13.320Z,1578440713.320 [SBIT] Loaded
2020-01-07T23:45:13.321Z,1578440713.321 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-07T23:45:13.322Z,1578440713.322 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-07T23:45:13.334Z,1578440713.334 [IBIT] Loaded
2020-01-07T23:45:13.334Z,1578440713.334 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-07T23:45:13.338Z,1578440713.338 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-07T23:45:13.479Z,1578440713.479 [CBIT] Loaded
2020-01-07T23:45:13.479Z,1578440713.479 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-07T23:45:13.479Z,1578440713.479 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-07T23:45:13.483Z,1578440713.483 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-07T23:45:13.484Z,1578440713.484 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-07T23:45:13.491Z,1578440713.491 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-07T23:45:13.492Z,1578440713.492 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0
2020-01-07T23:45:13.492Z,1578440713.492 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7687
2020-01-07T23:45:13.498Z,1578440713.498 [Supervisor](INFO): Main Thread ID is 7596
2020-01-07T23:45:13.498Z,1578440713.498 [Supervisor](DEBUG): Running supervisor.
2020-01-07T23:45:13.498Z,1578440713.498 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7688
2020-01-07T23:45:13.502Z,1578440713.502 [controlThread ThreadHandler](INFO): Handler Thread ID is 7689
2020-01-07T23:45:13.503Z,1578440713.503 [controlThread](DEBUG): Initializing ControlThread
2020-01-07T23:45:13.503Z,1578440713.503 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-07T23:45:13.505Z,1578440713.505 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-07T23:45:13.506Z,1578440713.506 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-07T23:45:13.506Z,1578440713.506 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-07T23:45:13.508Z,1578440713.508 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-07T23:45:13.509Z,1578440713.509 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-07T23:45:13.515Z,1578440713.515 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-07T23:45:13.515Z,1578440713.515 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-07T23:45:13.515Z,1578440713.515 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-07T23:45:13.516Z,1578440713.516 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-07T23:45:13.516Z,1578440713.516 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-07T23:45:13.517Z,1578440713.517 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-07T23:45:13.522Z,1578440713.522 [SBIT](INFO): Initialize SBIT Component.
2020-01-07T23:45:13.522Z,1578440713.522 [SBIT](IMPORTANT): git: 2019-11-11-3-gd320370
2020-01-07T23:45:13.522Z,1578440713.522 [SBIT](INFO): git hash: d3203708d3f1ecd20247ca5ed6b734626dd89ff5
2020-01-07T23:45:13.523Z,1578440713.523 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-07T23:45:13.524Z,1578440713.524 [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
2020-01-07T23:45:13.525Z,1578440713.525 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2020-01-07T23:45:13.525Z,1578440713.525 [IBIT](INFO): Initialize IBIT Component.
2020-01-07T23:45:13.526Z,1578440713.526 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-07T23:45:13.527Z,1578440713.527 [logger ThreadHandler](INFO): Handler Thread ID is 7690
2020-01-07T23:45:13.537Z,1578440713.537 [CBIT](DEBUG): Initialized mux pins.
2020-01-07T23:45:13.537Z,1578440713.537 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-07T23:45:13.546Z,1578440713.546 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7691
2020-01-07T23:45:13.547Z,1578440713.547 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-07T23:45:13.558Z,1578440713.558 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7692
2020-01-07T23:45:13.561Z,1578440713.561 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-07T23:45:13.561Z,1578440713.561 [CBIT](DEBUG): Initializing heartbeat.
2020-01-07T23:45:13.570Z,1578440713.570 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7693
2020-01-07T23:45:13.570Z,1578440713.570 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-01-07T23:45:13.574Z,1578440713.574 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-01-07T23:45:13.575Z,1578440713.575 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7695
2020-01-07T23:45:13.576Z,1578440713.576 [WetLabsBB2FL](INFO): Powering down
2020-01-07T23:45:13.620Z,1578440713.620 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7696
2020-01-07T23:45:13.627Z,1578440713.627 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-07T23:45:13.627Z,1578440713.627 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-07T23:45:13.628Z,1578440713.628 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-07T23:45:13.628Z,1578440713.628 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-07T23:45:13.628Z,1578440713.628 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-07T23:45:13.628Z,1578440713.628 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-07T23:45:13.628Z,1578440713.628 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-07T23:45:13.629Z,1578440713.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-07T23:45:13.629Z,1578440713.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-07T23:45:13.629Z,1578440713.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-07T23:45:13.629Z,1578440713.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-07T23:45:13.630Z,1578440713.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-07T23:45:13.630Z,1578440713.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-07T23:45:13.630Z,1578440713.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-07T23:45:13.630Z,1578440713.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-07T23:45:13.631Z,1578440713.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-07T23:45:13.633Z,1578440713.633 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-07T23:45:13.634Z,1578440713.634 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-07T23:45:13.669Z,1578440713.669 [CBIT](DEBUG): Backplane powered.
2020-01-07T23:45:13.770Z,1578440713.770 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-07T23:45:13.874Z,1578440713.874 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-07T23:45:13.897Z,1578440713.897 [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>


2020-01-07T23:45:13.937Z,1578440713.937 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-07T23:45:13.961Z,1578440713.961 [Radio_Surface](INFO): Powering up
2020-01-07T23:45:14.111Z,1578440714.111 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-07T23:45:14.112Z,1578440714.112 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-07T23:45:14.114Z,1578440714.114 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-07T23:45:14.157Z,1578440714.157 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-07T23:45:14.175Z,1578440714.175 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-07T23:45:14.192Z,1578440714.192 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-07T23:45:14.195Z,1578440714.195 [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>


2020-01-07T23:45:14.205Z,1578440714.205 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,Onboard,BPC1,CANONSampler,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,
2020-01-07T23:45:14.217Z,1578440714.217 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-07T23:45:14.406Z,1578440714.406 [CANONSampler](INFO): Powering down
2020-01-07T23:45:14.494Z,1578440714.494 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-07T23:45:14.502Z,1578440714.502 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-07T23:45:14.503Z,1578440714.503 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-07T23:45:14.510Z,1578440714.510 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-07T23:45:14.510Z,1578440714.510 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-07T23:45:14.518Z,1578440714.518 [MassServo](DEBUG): Initializing MassServo.
2020-01-07T23:45:14.518Z,1578440714.518 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-07T23:45:14.526Z,1578440714.526 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-07T23:45:14.526Z,1578440714.526 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-07T23:45:14.534Z,1578440714.534 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-07T23:45:15.385Z,1578440715.385 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-07T23:45:15.385Z,1578440715.385 [RudderServo](FAULT): Rudder failed to initialize
2020-01-07T23:45:15.386Z,1578440715.386 [RudderServo] Communications Fault, FailCount= 1
2020-01-07T23:45:15.386Z,1578440715.386 [RudderServo](ERROR): Communications Fault
2020-01-07T23:45:15.509Z,1578440715.509 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-07T23:45:15.694Z,1578440715.694 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-07T23:45:15.694Z,1578440715.694 [RudderServo](INFO): Powering down
2020-01-07T23:45:16.355Z,1578440716.355 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-07T23:45:16.474Z,1578440716.474 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-07T23:45:16.478Z,1578440716.478 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-07T23:45:16.478Z,1578440716.478 [RudderServo] No Fault, FailCount= 1
2020-01-07T23:45:30.591Z,1578440730.591 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004627
2020-01-07T23:45:40.571Z,1578440740.571 [NAL9602](INFO): Powering up NAL9602
2020-01-07T23:45:51.478Z,1578440751.478 [NAL9602](INFO): NAL9602 initialized
2020-01-07T23:47:29.804Z,1578440849.804 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-07T23:47:46.109Z,1578440866.109 [CommandLine](IMPORTANT): got command failComponent
2020-01-07T23:47:46.109Z,1578440866.109 [CommandLine](IMPORTANT): Failed components:
2020-01-07T23:47:46.109Z,1578440866.109 [CommandLine](IMPORTANT): No failed Components.
2020-01-07T23:47:54.335Z,1578440874.335 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-07T23:47:54.340Z,1578440874.340 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-07T23:47:56.997Z,1578440876.997 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-01-07T23:48:05.147Z,1578440885.147 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002997
CHAN A1 (24V): -0.180512
CHAN A2 (12V): -0.002641
CHAN A3 (5V): -0.001303
CHAN B0 (3.3V): -0.000098
CHAN B1 (3.15aV): 4.767929
CHAN B2 (3.15bV): -0.000461
CHAN B3 (GND): -0.000428
OPEN: -0.000476
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-07T23:48:13.780Z,1578440893.780 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-07T23:48:13.780Z,1578440893.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-07T23:48:13.882Z,1578440893.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-07T23:48:14.274Z,1578440894.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-07T23:48:14.274Z,1578440894.274 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-07T23:48:23.179Z,1578440903.179 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-01-07T23:48:48.148Z,1578440928.148 [SBIT](IMPORTANT): SBIT PASSED
2020-01-07T23:48:48.196Z,1578440928.196 [CommandLine](IMPORTANT): got command configSet list
2020-01-07T23:48:48.197Z,1578440928.197 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-07T23:48:48.197Z,1578440928.197 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): CTD_Seabird.verbosity=0 count;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): VerticalControl.massDefault=-35 millimeter;

2020-01-07T23:48:48.198Z,1578440928.198 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-01-07T23:48:48.527Z,1578440928.527 [MissionManager](IMPORTANT): Started mission Startup
2020-01-07T23:48:48.528Z,1578440928.528 [Startup] Running Loop=1
2020-01-07T23:48:48.528Z,1578440928.528 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-07T23:48:48.528Z,1578440928.528 [Startup:A.GoToSurface] Running Loop=1
2020-01-07T23:48:48.528Z,1578440928.528 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-07T23:48:48.529Z,1578440928.529 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-07T23:48:48.545Z,1578440928.545 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-07T23:48:48.546Z,1578440928.546 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-07T23:48:48.546Z,1578440928.546 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-07T23:48:48.546Z,1578440928.546 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-07T23:48:48.548Z,1578440928.548 [Startup:StartupSatComms] Running Loop=1
2020-01-07T23:48:48.548Z,1578440928.548 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-07T23:48:48.548Z,1578440928.548 [Startup:StartupSatComms:A] Running Loop=1
2020-01-07T23:48:48.924Z,1578440928.924 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-07T23:48:49.782Z,1578440929.782 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-01-07T23:49:48.727Z,1578440988.727 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-07T23:48:48.5Z
2020-01-07T23:49:48.727Z,1578440988.727 [Startup:StartupSatComms:A] Stopped
2020-01-07T23:49:48.727Z,1578440988.727 [Startup:StartupSatComms:B] Running Loop=1
2020-01-07T23:49:49.135Z,1578440989.135 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-07T23:49:57.619Z,1578440997.619 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20200106T195438/Courier0087.lzma
2020-01-07T23:49:59.623Z,1578440999.623 [DataOverHttps](INFO): Moved sent file to Logs/20200106T195438/Courier0087.lzma.bak
2020-01-07T23:49:59.623Z,1578440999.623 [DataOverHttps](INFO): SBD MOMSN=12185605
2020-01-07T23:50:16.471Z,1578441016.471 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20200106T195438/Express0088.lzma
2020-01-07T23:50:18.475Z,1578441018.475 [DataOverHttps](INFO): Moved sent file to Logs/20200106T195438/Express0088.lzma.bak
2020-01-07T23:50:18.475Z,1578441018.475 [DataOverHttps](INFO): SBD MOMSN=12185607
2020-01-07T23:50:44.175Z,1578441044.175 [DataOverHttps](INFO): Sending 831 bytes from file Logs/20200107T234502/Express0001.lzma
2020-01-07T23:50:46.565Z,1578441046.565 [DataOverHttps](INFO): Moved sent file to Logs/20200107T234502/Express0001.lzma.bak
2020-01-07T23:50:46.565Z,1578441046.565 [DataOverHttps](INFO): SBD MOMSN=12185609
2020-01-07T23:50:47.710Z,1578441047.710 [Startup:StartupSatComms:B] Stopped
2020-01-07T23:50:47.710Z,1578441047.710 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-07T23:50:47.710Z,1578441047.710 [Startup:StartupSatComms] Stopped
2020-01-07T23:50:47.711Z,1578441047.711 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-07T23:50:47.711Z,1578441047.711 [Startup](INFO): Completed Startup
2020-01-07T23:50:47.711Z,1578441047.711 [MissionManager](INFO): Startup is completed.
2020-01-07T23:50:47.712Z,1578441047.712 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-07T23:50:47.712Z,1578441047.712 [Startup] Stopped
2020-01-07T23:50:47.712Z,1578441047.712 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-07T23:50:47.712Z,1578441047.712 [Startup:A.GoToSurface] Stopped
2020-01-07T23:50:47.712Z,1578441047.712 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-07T23:50:48.115Z,1578441048.115 [MissionManager](IMPORTANT): Started mission Default
2020-01-07T23:50:48.115Z,1578441048.115 [Default] Running Loop=1
2020-01-07T23:50:48.115Z,1578441048.115 [Default](DEBUG): Aggregate::initialize Default
2020-01-07T23:50:48.115Z,1578441048.115 [Default:B.GoToSurface] Running Loop=1
2020-01-07T23:50:48.115Z,1578441048.115 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-07T23:50:48.116Z,1578441048.116 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-07T23:50:48.116Z,1578441048.116 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-07T23:50:48.116Z,1578441048.116 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-07T23:50:48.117Z,1578441048.117 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-07T23:50:48.117Z,1578441048.117 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-07T23:50:48.117Z,1578441048.117 [Default:A.Wait] Running Loop=1
2020-01-07T23:50:48.117Z,1578441048.117 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-07T23:50:54.942Z,1578441054.942 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-07T23:51:01.431Z,1578441061.431 [Default:A.Wait](INFO): Done Waiting.
2020-01-07T23:51:01.431Z,1578441061.431 [Default:A.Wait] Stopped
2020-01-07T23:51:01.431Z,1578441061.431 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-07T23:51:01.860Z,1578441061.860 [Default:CheckIn] Running Loop=1
2020-01-07T23:51:01.860Z,1578441061.860 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-07T23:51:01.860Z,1578441061.860 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-07T23:51:02.264Z,1578441062.264 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-07T23:51:14.771Z,1578441074.771 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-07T23:51:14.771Z,1578441074.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-07T23:51:14.781Z,1578441074.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-07T23:51:15.184Z,1578441075.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-07T23:51:15.184Z,1578441075.184 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-07T23:51:33.419Z,1578441093.419 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-01-07T23:51:46.340Z,1578441106.340 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-01-07T23:53:01.831Z,1578441181.831 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-07T23:53:01.835Z,1578441181.835 [BPC1](INFO): Received data from all battery sticks.
2020-01-07T23:54:15.774Z,1578441255.774 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-07T23:54:15.774Z,1578441255.774 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-07T23:54:15.796Z,1578441255.796 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-07T23:54:16.175Z,1578441256.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-07T23:54:16.175Z,1578441256.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-07T23:55:55.121Z,1578441355.121 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-07T23:55:55.121Z,1578441355.121 [NAL9602] Data Fault, FailCount= 1
2020-01-07T23:55:55.121Z,1578441355.121 [NAL9602](ERROR): Data Fault
2020-01-07T23:55:55.152Z,1578441355.152 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-07T23:55:55.518Z,1578441355.518 [NAL9602](INFO): Powering down
2020-01-07T23:55:56.364Z,1578441356.364 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-07T23:55:56.364Z,1578441356.364 [NAL9602] No Fault, FailCount= 1
2020-01-07T23:56:02.010Z,1578441362.010 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-07T23:51:01.9Z
2020-01-07T23:56:02.011Z,1578441362.011 [Default:CheckIn:Read_GPS] Stopped
2020-01-07T23:56:02.011Z,1578441362.011 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-07T23:56:02.423Z,1578441362.423 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-07T23:56:10.093Z,1578441370.093 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20200107T234502/Courier0004.lzma
2020-01-07T23:56:12.099Z,1578441372.099 [DataOverHttps](INFO): Moved sent file to Logs/20200107T234502/Courier0004.lzma.bak
2020-01-07T23:56:12.099Z,1578441372.099 [DataOverHttps](INFO): SBD MOMSN=12185612
2020-01-07T23:56:25.818Z,1578441385.818 [NAL9602](INFO): Powering up NAL9602
2020-01-07T23:56:32.753Z,1578441392.753 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20200107T234502/Express0005.lzma
2020-01-07T23:56:34.759Z,1578441394.759 [DataOverHttps](INFO): Moved sent file to Logs/20200107T234502/Express0005.lzma.bak
2020-01-07T23:56:34.759Z,1578441394.759 [DataOverHttps](INFO): SBD MOMSN=12185614
2020-01-07T23:56:35.943Z,1578441395.943 [Default:CheckIn:Read_Iridium] Stopped
2020-01-07T23:56:35.943Z,1578441395.943 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-07T23:56:35.943Z,1578441395.943 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-07T23:56:36.727Z,1578441396.727 [NAL9602](INFO): NAL9602 initialized
2020-01-07T23:57:16.770Z,1578441436.770 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-07T23:57:16.770Z,1578441436.770 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-07T23:57:16.780Z,1578441436.780 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-07T23:57:17.231Z,1578441437.231 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-07T23:57:17.231Z,1578441437.231 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-08T00:00:17.759Z,1578441617.759 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-08T00:00:17.759Z,1578441617.759 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-08T00:00:17.769Z,1578441617.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-08T00:00:18.152Z,1578441618.152 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-08T00:00:18.152Z,1578441618.152 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-08T00:01:36.534Z,1578441696.534 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-08T00:01:36.534Z,1578441696.534 [Default:CheckIn:C.Wait] Stopped
2020-01-08T00:01:36.534Z,1578441696.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-08T00:01:36.534Z,1578441696.534 [Default:CheckIn:D] Running Loop=1
2020-01-08T00:01:36.947Z,1578441696.947 [Default:CheckIn:D] Stopped
2020-01-08T00:01:36.947Z,1578441696.947 [Default:CheckIn:E] Running Loop=1
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.813863 min 
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn:E] Stopped
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn] Stopped
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn](INFO): Running loop #2
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn] Running Loop=2
2020-01-08T00:01:37.351Z,1578441697.351 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-08T00:01:37.352Z,1578441697.352 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-08T00:03:18.758Z,1578441798.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-01-08T00:03:18.759Z,1578441798.759 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-08T00:03:18.777Z,1578441798.777 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-08T00:03:19.154Z,1578441799.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-08T00:03:19.154Z,1578441799.154 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-01-08T00:05:23.638Z,1578441923.638 [CBIT](CRITICAL): run-time exception #7
2020-01-08T00:05:23.638Z,1578441923.638 [CBIT](CRITICAL): Bad address: 0x4041E008
2020-01-08T00:05:23.638Z,1578441923.638 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x17a5e8]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x17a184]

2020-01-08T00:05:23.639Z,1578441923.639 [CBIT] Software Fault, FailCount= 1
2020-01-08T00:05:23.639Z,1578441923.639 [CBIT](ERROR): Software Fault
2020-01-08T00:05:23.669Z,1578441923.669 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-08T00:05:23.670Z,1578441923.670 [SyncHandler](INFO): Protected caller Thread ID is 7732
2020-01-08T00:05:23.994Z,1578441923.994 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-08T00:05:23.994Z,1578441923.994 [CBIT](DEBUG): Powering off loads.
2020-01-08T00:05:24.005Z,1578441924.005 [CBIT](DEBUG): Disabling WDT.
2020-01-08T00:05:24.017Z,1578441924.017 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-08T00:05:34.457Z,1578441934.457 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-01-08T00:05:34.458Z,1578441934.458 [AHRS_M2] Data Fault, FailCount= 1
2020-01-08T00:05:34.458Z,1578441934.458 [AHRS_M2](ERROR): Data Fault
2020-01-08T00:05:34.849Z,1578441934.849 [AHRS_M2](INFO): Powering down
2020-01-08T00:05:59.605Z,1578441959.605 [NAL9602](ERROR): getFix uart error: serial timeout
2020-01-08T00:06:12.726Z,1578441972.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-08T00:06:14.006Z,1578441974.006 [CommandLine](IMPORTANT): got command quit
2020-01-08T00:06:15.009Z,1578441975.009 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-01-08T00:06:15.010Z,1578441975.010 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:15.010Z,1578441975.010 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:15.149Z,1578441975.149 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-08T00:06:15.149Z,1578441975.149 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:15.150Z,1578441975.150 [CommandLine](INFO): Join timeout helper Thread ID is 8038
2020-01-08T00:06:15.150Z,1578441975.150 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-08T00:06:15.150Z,1578441975.150 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:15.151Z,1578441975.151 [NavChartDb](INFO): Join timeout helper Thread ID is 8039
2020-01-08T00:06:15.549Z,1578441975.549 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:15.550Z,1578441975.550 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:15.561Z,1578441975.561 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-01-08T00:06:15.561Z,1578441975.561 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:15.562Z,1578441975.562 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8042
2020-01-08T00:06:15.894Z,1578441975.894 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:15.894Z,1578441975.894 [WetLabsBB2FL](INFO): Powering down
2020-01-08T00:06:15.895Z,1578441975.895 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:15.901Z,1578441975.901 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-01-08T00:06:15.901Z,1578441975.901 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:15.902Z,1578441975.902 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8045
2020-01-08T00:06:16.734Z,1578441976.734 [CTD_Seabird](INFO): Powering down
2020-01-08T00:06:16.745Z,1578441976.745 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:16.746Z,1578441976.746 [CTD_Seabird](INFO): Powering down
2020-01-08T00:06:16.759Z,1578441976.759 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:16.773Z,1578441976.773 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-08T00:06:16.773Z,1578441976.773 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:16.774Z,1578441976.774 [Radio_Surface](INFO): Join timeout helper Thread ID is 8052
2020-01-08T00:06:17.037Z,1578441977.037 [Radio_Surface](INFO): Powering down
2020-01-08T00:06:17.038Z,1578441977.038 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:17.038Z,1578441977.038 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.046Z,1578441977.046 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-08T00:06:17.047Z,1578441977.047 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.047Z,1578441977.047 [DataOverHttps](INFO): Join timeout helper Thread ID is 8054
2020-01-08T00:06:17.745Z,1578441977.745 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:17.746Z,1578441977.746 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.766Z,1578441977.766 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-08T00:06:17.766Z,1578441977.766 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.766Z,1578441977.766 [logger](INFO): Join timeout helper Thread ID is 8058
2020-01-08T00:06:17.793Z,1578441977.793 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:17.794Z,1578441977.794 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.806Z,1578441977.806 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-08T00:06:17.806Z,1578441977.806 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.806Z,1578441977.806 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-08T00:06:17.806Z,1578441977.806 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.807Z,1578441977.807 [controlThread](INFO): Join timeout helper Thread ID is 8059
2020-01-08T00:06:17.838Z,1578441977.838 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-08T00:06:17.838Z,1578441977.838 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-08T00:06:17.838Z,1578441977.838 [AHRS_M2](INFO): Powering down
2020-01-08T00:06:17.839Z,1578441977.839 [NAL9602](INFO): Powering down
2020-01-08T00:06:17.841Z,1578441977.841 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-08T00:06:17.842Z,1578441977.842 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-08T00:06:17.842Z,1578441977.842 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-08T00:06:17.843Z,1578441977.843 [MissionManager](INFO): Uninitializing Mission Default
2020-01-08T00:06:17.843Z,1578441977.843 [Default] Stopped
2020-01-08T00:06:17.843Z,1578441977.843 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-08T00:06:17.843Z,1578441977.843 [Default:B.GoToSurface] Stopped
2020-01-08T00:06:17.843Z,1578441977.843 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-08T00:06:17.843Z,1578441977.843 [Default:CheckIn] Stopped
2020-01-08T00:06:17.843Z,1578441977.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-08T00:06:17.843Z,1578441977.843 [Default:CheckIn:Read_GPS] Stopped
2020-01-08T00:06:17.846Z,1578441977.846 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-08T00:06:17.846Z,1578441977.846 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-08T00:06:17.846Z,1578441977.846 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-08T00:06:17.847Z,1578441977.847 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-08T00:06:17.847Z,1578441977.847 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-08T00:06:17.847Z,1578441977.847 [BuoyancyServo](INFO): Powering down
2020-01-08T00:06:17.862Z,1578441977.862 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-08T00:06:17.862Z,1578441977.862 [ElevatorServo](INFO): Powering down
2020-01-08T00:06:17.863Z,1578441977.863 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-08T00:06:17.863Z,1578441977.863 [MassServo](INFO): Powering down
2020-01-08T00:06:17.863Z,1578441977.863 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-08T00:06:17.864Z,1578441977.864 [RudderServo](INFO): Powering down
2020-01-08T00:06:17.864Z,1578441977.864 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-08T00:06:17.864Z,1578441977.864 [ThrusterServo](INFO): Powering down
2020-01-08T00:06:17.865Z,1578441977.865 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-08T00:06:17.866Z,1578441977.866 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-08T00:06:17.866Z,1578441977.866 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-08T00:06:17.866Z,1578441977.866 [CBIT](DEBUG): Powering off loads.
2020-01-08T00:06:17.877Z,1578441977.877 [CBIT](DEBUG): Disabling WDT.
2020-01-08T00:06:17.889Z,1578441977.889 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-08T00:06:17.890Z,1578441977.890 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.976Z,1578441977.976 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:17.982Z,1578441977.982 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:18.011Z,1578441978.011 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:18.015Z,1578441978.015 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:18.051Z,1578441978.051 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-08T00:06:18.111Z,1578441978.111 [logger ThreadHandler](INFO): Thread cancelled.