2020-06-15T16:05:56.301Z,1592237156.301 [Supervisor](DEBUG): Initializing supervisor.
2020-06-15T16:05:56.303Z,1592237156.303 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-06-15T16:05:56.304Z,1592237156.304 [SyncHandler](INFO): Protected caller Thread ID is 2017
2020-06-15T16:05:56.304Z,1592237156.304 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-06-15T16:05:56.305Z,1592237156.305 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-06-15T16:05:56.305Z,1592237156.305 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2018
2020-06-15T16:05:56.308Z,1592237156.308 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-06-15T16:05:56.321Z,1592237156.321 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-06-15T16:05:56.322Z,1592237156.322 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-06-15T16:05:56.322Z,1592237156.322 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2019
2020-06-15T16:05:56.323Z,1592237156.323 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-06-15T16:05:56.323Z,1592237156.323 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-06-15T16:05:56.324Z,1592237156.324 [logger ThreadHandler](INFO): Protected caller Thread ID is 2020
2020-06-15T16:05:56.326Z,1592237156.326 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-06-15T16:05:56.326Z,1592237156.326 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-06-15T16:05:56.328Z,1592237156.328 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-06-15T16:05:56.640Z,1592237156.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-06-15T16:05:56.640Z,1592237156.640 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-06-15T16:05:57.239Z,1592237157.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-06-15T16:05:57.239Z,1592237157.239 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-06-15T16:05:57.385Z,1592237157.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-06-15T16:05:57.386Z,1592237157.386 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-06-15T16:05:57.851Z,1592237157.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-06-15T16:05:57.852Z,1592237157.852 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-06-15T16:05:57.949Z,1592237157.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-06-15T16:05:57.950Z,1592237157.950 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-06-15T16:05:58.032Z,1592237158.032 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-06-15T16:05:58.373Z,1592237158.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-06-15T16:05:58.374Z,1592237158.374 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-06-15T16:05:58.522Z,1592237158.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-06-15T16:05:58.523Z,1592237158.523 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-06-15T16:05:58.629Z,1592237158.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-06-15T16:05:58.630Z,1592237158.630 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-06-15T16:05:58.925Z,1592237158.925 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-06-15T16:05:58.925Z,1592237158.925 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-06-15T16:05:59.481Z,1592237159.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-06-15T16:05:59.482Z,1592237159.482 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-06-15T16:05:59.900Z,1592237159.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-06-15T16:05:59.901Z,1592237159.901 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-06-15T16:06:00.004Z,1592237160.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-06-15T16:06:00.005Z,1592237160.005 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-06-15T16:06:00.196Z,1592237160.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-06-15T16:06:00.197Z,1592237160.197 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-06-15T16:06:00.403Z,1592237160.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-06-15T16:06:00.403Z,1592237160.403 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-06-15T16:06:00.628Z,1592237160.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-06-15T16:06:00.630Z,1592237160.630 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2020-06-15T16:06:00.631Z,1592237160.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2020-06-15T16:06:00.726Z,1592237160.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2020-06-15T16:06:00.866Z,1592237160.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2020-06-15T16:06:00.953Z,1592237160.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2020-06-15T16:06:01.037Z,1592237161.037 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2020-06-15T16:06:01.144Z,1592237161.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2020-06-15T16:06:01.339Z,1592237161.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2020-06-15T16:06:02.324Z,1592237162.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-06-15T16:06:02.325Z,1592237162.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2020-06-15T16:06:02.475Z,1592237162.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2020-06-15T16:06:02.625Z,1592237162.625 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2020-06-15T16:06:02.725Z,1592237162.725 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2020-06-15T16:06:02.820Z,1592237162.820 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2020-06-15T16:06:02.820Z,1592237162.820 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-06-15T16:06:02.829Z,1592237162.829 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-06-15T16:06:02.906Z,1592237162.906 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-06-15T16:06:02.906Z,1592237162.906 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-06-15T16:06:02.925Z,1592237162.925 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-06-15T16:06:02.925Z,1592237162.925 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-06-15T16:06:02.966Z,1592237162.966 [DepthRateCalculator] Loaded
2020-06-15T16:06:02.966Z,1592237162.966 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-06-15T16:06:02.972Z,1592237162.972 [PitchRateCalculator] Loaded
2020-06-15T16:06:02.972Z,1592237162.972 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-06-15T16:06:02.988Z,1592237162.988 [SpeedCalculator] Loaded
2020-06-15T16:06:02.988Z,1592237162.988 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-06-15T16:06:03.010Z,1592237163.010 [TempGradientCalculator] Loaded
2020-06-15T16:06:03.010Z,1592237163.010 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-06-15T16:06:03.015Z,1592237163.015 [YawRateCalculator] Loaded
2020-06-15T16:06:03.016Z,1592237163.016 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-06-15T16:06:03.055Z,1592237163.055 [ElevatorOffsetCalculator] Loaded
2020-06-15T16:06:03.055Z,1592237163.055 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-06-15T16:06:03.056Z,1592237163.056 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-06-15T16:06:03.056Z,1592237163.056 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-06-15T16:06:03.104Z,1592237163.104 [VerticalControl](DEBUG): Construct VerticalControl.
2020-06-15T16:06:03.202Z,1592237163.202 [VerticalControl] Loaded
2020-06-15T16:06:03.202Z,1592237163.202 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-06-15T16:06:03.202Z,1592237163.202 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-06-15T16:06:03.282Z,1592237163.282 [HorizontalControl] Loaded
2020-06-15T16:06:03.283Z,1592237163.283 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-06-15T16:06:03.283Z,1592237163.283 [SpeedControl](DEBUG): Construct SpeedControl.
2020-06-15T16:06:03.288Z,1592237163.288 [SpeedControl] Loaded
2020-06-15T16:06:03.289Z,1592237163.289 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-06-15T16:06:03.289Z,1592237163.289 [LoopControl](DEBUG): Construct LoopControl.
2020-06-15T16:06:03.290Z,1592237163.290 [LoopControl] Loaded
2020-06-15T16:06:03.290Z,1592237163.290 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-06-15T16:06:03.290Z,1592237163.290 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-06-15T16:06:03.291Z,1592237163.291 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-06-15T16:06:03.406Z,1592237163.406 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-06-15T16:06:03.406Z,1592237163.406 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-06-15T16:06:03.426Z,1592237163.426 [NavChart] Loaded
2020-06-15T16:06:03.426Z,1592237163.426 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-06-15T16:06:03.430Z,1592237163.430 [UniversalFixResidualReporter] Loaded
2020-06-15T16:06:03.431Z,1592237163.431 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-06-15T16:06:03.431Z,1592237163.431 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-06-15T16:06:03.431Z,1592237163.431 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-06-15T16:06:03.532Z,1592237163.532 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-06-15T16:06:03.532Z,1592237163.532 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-06-15T16:06:03.821Z,1592237163.821 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-06-15T16:06:03.826Z,1592237163.826 [AHRS_M2](INFO): created writer for : platform_orientation
2020-06-15T16:06:03.828Z,1592237163.828 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-06-15T16:06:03.833Z,1592237163.833 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-06-15T16:06:03.833Z,1592237163.833 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-06-15T16:06:03.838Z,1592237163.838 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-06-15T16:06:03.839Z,1592237163.839 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-06-15T16:06:03.844Z,1592237163.844 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-06-15T16:06:03.915Z,1592237163.915 [AHRS_M2] Loaded
2020-06-15T16:06:03.915Z,1592237163.915 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-06-15T16:06:03.994Z,1592237163.994 [DataOverHttps] Loaded
2020-06-15T16:06:03.994Z,1592237163.994 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-06-15T16:06:03.995Z,1592237163.995 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408544E0
2020-06-15T16:06:03.996Z,1592237163.996 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2115
2020-06-15T16:06:04.009Z,1592237164.009 [Depth_Keller] Loaded
2020-06-15T16:06:04.009Z,1592237164.009 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-06-15T16:06:04.055Z,1592237164.055 [DVL_micro] Loaded
2020-06-15T16:06:04.055Z,1592237164.055 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-06-15T16:06:04.150Z,1592237164.150 [NAL9602] Loaded
2020-06-15T16:06:04.151Z,1592237164.151 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-06-15T16:06:04.190Z,1592237164.190 [Onboard] Loaded
2020-06-15T16:06:04.190Z,1592237164.190 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-06-15T16:06:04.194Z,1592237164.194 [Radio_Surface] Loaded
2020-06-15T16:06:04.194Z,1592237164.194 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-06-15T16:06:04.195Z,1592237164.195 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2020-06-15T16:06:04.195Z,1592237164.195 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2116
2020-06-15T16:06:05.728Z,1592237165.728 [BPC1] Loaded
2020-06-15T16:06:05.729Z,1592237165.729 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-06-15T16:06:05.729Z,1592237165.729 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-06-15T16:06:05.730Z,1592237165.730 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-06-15T16:06:05.833Z,1592237165.833 [BuoyancyServo] Loaded
2020-06-15T16:06:05.834Z,1592237165.834 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-06-15T16:06:05.845Z,1592237165.845 [ElevatorServo] Loaded
2020-06-15T16:06:05.845Z,1592237165.845 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-06-15T16:06:05.856Z,1592237165.856 [MassServo] Loaded
2020-06-15T16:06:05.856Z,1592237165.856 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-06-15T16:06:05.867Z,1592237165.867 [RudderServo] Loaded
2020-06-15T16:06:05.867Z,1592237165.867 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-06-15T16:06:05.878Z,1592237165.878 [ThrusterServo] Loaded
2020-06-15T16:06:05.878Z,1592237165.878 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-06-15T16:06:05.879Z,1592237165.879 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-06-15T16:06:05.879Z,1592237165.879 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-06-15T16:06:05.959Z,1592237165.959 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-06-15T16:06:05.960Z,1592237165.960 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-06-15T16:06:05.986Z,1592237165.986 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-06-15T16:06:05.986Z,1592237165.986 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-06-15T16:06:06.368Z,1592237166.368 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-06-15T16:06:06.369Z,1592237166.369 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-06-15T16:06:06.725Z,1592237166.725 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-06-15T16:06:06.730Z,1592237166.730 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-06-15T16:06:06.731Z,1592237166.731 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-06-15T16:06:06.736Z,1592237166.736 [CTD_Seabird](INFO): created writer for : depth
2020-06-15T16:06:06.736Z,1592237166.736 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-06-15T16:06:06.741Z,1592237166.741 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-06-15T16:06:06.742Z,1592237166.742 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-06-15T16:06:06.747Z,1592237166.747 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-06-15T16:06:06.747Z,1592237166.747 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-06-15T16:06:06.752Z,1592237166.752 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-06-15T16:06:06.753Z,1592237166.753 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-06-15T16:06:06.758Z,1592237166.758 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-06-15T16:06:06.759Z,1592237166.759 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-06-15T16:06:06.764Z,1592237166.764 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-06-15T16:06:06.791Z,1592237166.791 [CTD_Seabird] Loaded
2020-06-15T16:06:06.791Z,1592237166.791 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-06-15T16:06:06.792Z,1592237166.792 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A904E0
2020-06-15T16:06:06.792Z,1592237166.792 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2123
2020-06-15T16:06:06.823Z,1592237166.823 [ESPComponent] Loaded
2020-06-15T16:06:06.823Z,1592237166.823 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-06-15T16:06:06.837Z,1592237166.837 [PAR_Licor] Loaded
2020-06-15T16:06:06.838Z,1592237166.838 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-06-15T16:06:06.844Z,1592237166.844 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-06-15T16:06:06.844Z,1592237166.844 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-06-15T16:06:06.848Z,1592237166.848 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-06-15T16:06:06.848Z,1592237166.848 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-06-15T16:06:06.852Z,1592237166.852 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-06-15T16:06:06.853Z,1592237166.853 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-06-15T16:06:06.857Z,1592237166.857 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-06-15T16:06:06.857Z,1592237166.857 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-06-15T16:06:06.861Z,1592237166.861 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-06-15T16:06:06.861Z,1592237166.861 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-06-15T16:06:06.866Z,1592237166.866 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-06-15T16:06:06.866Z,1592237166.866 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-06-15T16:06:06.870Z,1592237166.870 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-06-15T16:06:06.870Z,1592237166.870 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-06-15T16:06:06.874Z,1592237166.874 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.879Z,1592237166.879 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.879Z,1592237166.879 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.879Z,1592237166.879 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.883Z,1592237166.883 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.883Z,1592237166.883 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.888Z,1592237166.888 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.888Z,1592237166.888 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-15T16:06:06.892Z,1592237166.892 [WetLabsBB2FL] Loaded
2020-06-15T16:06:06.893Z,1592237166.893 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-06-15T16:06:06.894Z,1592237166.894 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC04E0
2020-06-15T16:06:06.894Z,1592237166.894 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2124
2020-06-15T16:06:06.895Z,1592237166.895 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-06-15T16:06:06.895Z,1592237166.895 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-06-15T16:06:07.031Z,1592237167.031 [SBIT](DEBUG): Construct Startup Built In Test.
2020-06-15T16:06:07.042Z,1592237167.042 [SBIT] Loaded
2020-06-15T16:06:07.043Z,1592237167.043 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-06-15T16:06:07.043Z,1592237167.043 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-06-15T16:06:07.055Z,1592237167.055 [IBIT] Loaded
2020-06-15T16:06:07.056Z,1592237167.056 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-06-15T16:06:07.059Z,1592237167.059 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-06-15T16:06:07.196Z,1592237167.196 [CBIT] Loaded
2020-06-15T16:06:07.196Z,1592237167.196 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-06-15T16:06:07.197Z,1592237167.197 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-06-15T16:06:07.200Z,1592237167.200 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-06-15T16:06:07.201Z,1592237167.201 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-06-15T16:06:07.207Z,1592237167.207 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-06-15T16:06:07.209Z,1592237167.209 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B2B4E0
2020-06-15T16:06:07.209Z,1592237167.209 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2125
2020-06-15T16:06:07.213Z,1592237167.213 [Supervisor](INFO): Main Thread ID is 2016
2020-06-15T16:06:07.214Z,1592237167.214 [Supervisor](DEBUG): Running supervisor.
2020-06-15T16:06:07.214Z,1592237167.214 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2126
2020-06-15T16:06:07.218Z,1592237167.218 [controlThread ThreadHandler](INFO): Handler Thread ID is 2127
2020-06-15T16:06:07.218Z,1592237167.218 [controlThread](DEBUG): Initializing ControlThread
2020-06-15T16:06:07.219Z,1592237167.219 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-06-15T16:06:07.219Z,1592237167.219 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-06-15T16:06:07.220Z,1592237167.220 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-06-15T16:06:07.220Z,1592237167.220 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-06-15T16:06:07.220Z,1592237167.220 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-06-15T16:06:07.221Z,1592237167.221 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-06-15T16:06:07.221Z,1592237167.221 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-06-15T16:06:07.223Z,1592237167.223 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-06-15T16:06:07.224Z,1592237167.224 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-06-15T16:06:07.224Z,1592237167.224 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-06-15T16:06:07.225Z,1592237167.225 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-06-15T16:06:07.226Z,1592237167.226 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-06-15T16:06:07.233Z,1592237167.233 [SBIT](INFO): Initialize SBIT Component.
2020-06-15T16:06:07.233Z,1592237167.233 [SBIT](IMPORTANT): git: 2020-06-09
2020-06-15T16:06:07.234Z,1592237167.234 [SBIT](INFO): git hash: 492c82351796d8646f9bb5d9c938bbc9efa48549
2020-06-15T16:06:07.234Z,1592237167.234 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-06-15T16:06:07.234Z,1592237167.234 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2020-06-15T16:06:07.235Z,1592237167.235 [SBIT](INFO): Beginning SBIT in 54.000000 seconds.
2020-06-15T16:06:07.236Z,1592237167.236 [IBIT](INFO): Initialize IBIT Component.
2020-06-15T16:06:07.237Z,1592237167.237 [CBIT](DEBUG): Initialize CBIT Component.
2020-06-15T16:06:07.238Z,1592237167.238 [logger ThreadHandler](INFO): Handler Thread ID is 2128
2020-06-15T16:06:07.249Z,1592237167.249 [CBIT](DEBUG): Initialized mux pins.
2020-06-15T16:06:07.249Z,1592237167.249 [CBIT](DEBUG): Initializing the watchdog timer.
2020-06-15T16:06:07.257Z,1592237167.257 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2129
2020-06-15T16:06:07.258Z,1592237167.258 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-06-15T16:06:07.269Z,1592237167.269 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2130
2020-06-15T16:06:07.273Z,1592237167.273 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-06-15T16:06:07.273Z,1592237167.273 [CBIT](DEBUG): Initializing heartbeat.
2020-06-15T16:06:07.281Z,1592237167.281 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2131
2020-06-15T16:06:07.282Z,1592237167.282 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-06-15T16:06:07.286Z,1592237167.286 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2133
2020-06-15T16:06:07.287Z,1592237167.287 [WetLabsBB2FL](INFO): Powering down
2020-06-15T16:06:07.317Z,1592237167.317 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2134
2020-06-15T16:06:07.320Z,1592237167.320 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-06-15T16:06:07.321Z,1592237167.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-06-15T16:06:07.321Z,1592237167.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-06-15T16:06:07.321Z,1592237167.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-06-15T16:06:07.321Z,1592237167.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-06-15T16:06:07.321Z,1592237167.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-06-15T16:06:07.322Z,1592237167.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-06-15T16:06:07.322Z,1592237167.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-06-15T16:06:07.322Z,1592237167.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-06-15T16:06:07.322Z,1592237167.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-06-15T16:06:07.322Z,1592237167.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-06-15T16:06:07.322Z,1592237167.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-06-15T16:06:07.323Z,1592237167.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-06-15T16:06:07.323Z,1592237167.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-06-15T16:06:07.323Z,1592237167.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-06-15T16:06:07.323Z,1592237167.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-06-15T16:06:07.345Z,1592237167.345 [CBIT](DEBUG): Deactivating GF circuits.
2020-06-15T16:06:07.345Z,1592237167.345 [CBIT](DEBUG): Deactivating emergency mode.
2020-06-15T16:06:07.381Z,1592237167.381 [CBIT](DEBUG): Backplane powered.
2020-06-15T16:06:07.382Z,1592237167.382 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-06-15T16:06:07.392Z,1592237167.392 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-15T16:06:07.402Z,1592237167.402 [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-06-15T16:06:07.403Z,1592237167.403 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-06-15T16:06:07.486Z,1592237167.486 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-06-15T16:06:07.487Z,1592237167.487 [Default:A.Wait](DEBUG): Construct Wait.
2020-06-15T16:06:07.489Z,1592237167.489 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-15T16:06:07.508Z,1592237167.508 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-06-15T16:06:07.535Z,1592237167.535 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-06-15T16:06:07.557Z,1592237167.557 [Default:E.Execute](DEBUG): Construct Execute.
2020-06-15T16:06:07.560Z,1592237167.560 [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-06-15T16:06:07.584Z,1592237167.584 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DVL_micro,NAL9602,Onboard,BPC1,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,
2020-06-15T16:06:07.597Z,1592237167.597 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-06-15T16:06:07.673Z,1592237167.673 [Radio_Surface](INFO): Powering up
2020-06-15T16:06:07.814Z,1592237167.814 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-06-15T16:06:07.821Z,1592237167.821 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-06-15T16:06:07.822Z,1592237167.822 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-06-15T16:06:07.829Z,1592237167.829 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-06-15T16:06:07.830Z,1592237167.830 [MassServo](DEBUG): Initializing EZServoServo.
2020-06-15T16:06:07.837Z,1592237167.837 [MassServo](DEBUG): Initializing MassServo.
2020-06-15T16:06:07.838Z,1592237167.838 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-15T16:06:07.845Z,1592237167.845 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-15T16:06:07.846Z,1592237167.846 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-06-15T16:06:07.853Z,1592237167.853 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-06-15T16:06:08.761Z,1592237168.761 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-06-15T16:06:08.761Z,1592237168.761 [RudderServo](FAULT): Rudder failed to initialize
2020-06-15T16:06:08.761Z,1592237168.761 [RudderServo] Communications Fault, FailCount= 1
2020-06-15T16:06:08.761Z,1592237168.761 [RudderServo](ERROR): Communications Fault
2020-06-15T16:06:08.872Z,1592237168.872 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-06-15T16:06:09.054Z,1592237169.054 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-15T16:06:09.054Z,1592237169.054 [RudderServo](INFO): Powering down
2020-06-15T16:06:09.747Z,1592237169.747 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-15T16:06:09.866Z,1592237169.866 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-15T16:06:09.870Z,1592237169.870 [CBIT](INFO): Clearing failed state for component RudderServo
2020-06-15T16:06:09.870Z,1592237169.870 [RudderServo] No Fault, FailCount= 1
2020-06-15T16:06:14.314Z,1592237174.314 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-06-15T16:06:21.975Z,1592237181.975 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004416
2020-06-15T16:06:25.046Z,1592237185.046 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:06:25.047Z,1592237185.047 [DVL_micro] Communications Fault, FailCount= 1
2020-06-15T16:06:25.047Z,1592237185.047 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:06:25.237Z,1592237185.237 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:06:25.525Z,1592237185.525 [DVL_micro](INFO): Powering down
2020-06-15T16:06:26.318Z,1592237186.318 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:06:26.318Z,1592237186.318 [DVL_micro] No Fault, FailCount= 1
2020-06-15T16:06:34.339Z,1592237194.339 [NAL9602](INFO): Powering up NAL9602
2020-06-15T16:06:44.030Z,1592237204.030 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:06:44.030Z,1592237204.030 [DVL_micro] Communications Fault, FailCount= 2
2020-06-15T16:06:44.030Z,1592237204.030 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:06:44.117Z,1592237204.117 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:06:44.509Z,1592237204.509 [DVL_micro](INFO): Powering down
2020-06-15T16:06:45.247Z,1592237205.247 [NAL9602](INFO): NAL9602 initialized
2020-06-15T16:06:45.334Z,1592237205.334 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:06:45.334Z,1592237205.334 [DVL_micro] No Fault, FailCount= 2
2020-06-15T16:07:01.871Z,1592237221.871 [SBIT](IMPORTANT): Beginning Startup BIT
2020-06-15T16:07:01.879Z,1592237221.879 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:07:03.024Z,1592237223.024 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:07:03.024Z,1592237223.024 [DVL_micro] Communications Fault, FailCount= 3
2020-06-15T16:07:03.024Z,1592237223.024 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:07:03.174Z,1592237223.174 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:07:03.174Z,1592237223.174 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-06-15T16:07:03.501Z,1592237223.501 [DVL_micro](INFO): Powering down
2020-06-15T16:07:03.695Z,1592237223.695 [CommandLine](FAULT): Scheduling is paused
2020-06-15T16:07:03.696Z,1592237223.696 [CBIT](INFO): Critical error at 20200615T160703
2020-06-15T16:07:03.696Z,1592237223.696 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-06-15T16:07:12.932Z,1592237232.932 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.098325
CHAN A1 (24V): -0.185260
CHAN A2 (12V): 0.032572
CHAN A3 (5V): -0.044981
CHAN B0 (3.3V): 0.004080
CHAN B1 (3.15aV): 0.004120
CHAN B2 (3.15bV): 0.132995
CHAN B3 (GND): -0.004707
OPEN: 0.000762
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:07:55.203Z,1592237275.203 [SBIT](IMPORTANT): SBIT PASSED
2020-06-15T16:07:55.203Z,1592237275.203 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-06-15T16:07:55.204Z,1592237275.204 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-06-15T16:07:55.204Z,1592237275.204 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2020-06-15T16:07:55.204Z,1592237275.204 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-06-15T16:07:55.205Z,1592237275.205 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-06-15T16:07:55.205Z,1592237275.205 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2020-06-15T16:07:55.205Z,1592237275.205 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-06-15T16:07:55.205Z,1592237275.205 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=222 cubic_centimeter;

2020-06-15T16:07:55.205Z,1592237275.205 [SBIT](IMPORTANT): VerticalControl.massDefault=8.6 millimeter;

2020-06-15T16:07:55.586Z,1592237275.586 [MissionManager](IMPORTANT): Started mission Startup
2020-06-15T16:07:55.586Z,1592237275.586 [Startup] Running Loop=1
2020-06-15T16:07:55.586Z,1592237275.586 [Startup](DEBUG): Aggregate::initialize Startup
2020-06-15T16:07:55.586Z,1592237275.586 [Startup:A.GoToSurface] Running Loop=1
2020-06-15T16:07:55.586Z,1592237275.586 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-15T16:07:55.587Z,1592237275.587 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-15T16:07:55.587Z,1592237275.587 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-15T16:07:55.588Z,1592237275.588 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-15T16:07:55.588Z,1592237275.588 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-15T16:07:55.589Z,1592237275.589 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-15T16:07:55.590Z,1592237275.590 [Startup:StartupSatComms] Running Loop=1
2020-06-15T16:07:55.590Z,1592237275.590 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-06-15T16:07:55.590Z,1592237275.590 [Startup:StartupSatComms:A] Running Loop=1
2020-06-15T16:07:55.983Z,1592237275.983 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-06-15T16:08:55.785Z,1592237335.785 [Startup:StartupSatComms:A](INFO): Timed out from 2020-06-15T16:07:55.6Z
2020-06-15T16:08:55.785Z,1592237335.785 [Startup:StartupSatComms:A] Stopped
2020-06-15T16:08:55.785Z,1592237335.785 [Startup:StartupSatComms:B] Running Loop=1
2020-06-15T16:08:56.179Z,1592237336.179 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-15T16:09:03.437Z,1592237343.437 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200611T161745/Courier0055.lzma
2020-06-15T16:09:04.439Z,1592237344.439 [DataOverHttps](INFO): Moved sent file to Logs/20200611T161745/Courier0055.lzma.bak
2020-06-15T16:09:04.439Z,1592237344.439 [DataOverHttps](INFO): SBD MOMSN=12390938
2020-06-15T16:09:07.541Z,1592237347.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-06-15T16:09:07.541Z,1592237347.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-15T16:09:07.559Z,1592237347.559 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-15T16:09:08.027Z,1592237348.027 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-15T16:09:08.027Z,1592237348.027 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-06-15T16:09:20.395Z,1592237360.395 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20200611T185815/Courier0000.lzma
2020-06-15T16:09:21.395Z,1592237361.395 [DataOverHttps](INFO): Moved sent file to Logs/20200611T185815/Courier0000.lzma.bak
2020-06-15T16:09:21.395Z,1592237361.395 [DataOverHttps](INFO): SBD MOMSN=12390940
2020-06-15T16:09:37.725Z,1592237377.725 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20200615T160556/Courier0000.lzma
2020-06-15T16:09:38.726Z,1592237378.726 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0000.lzma.bak
2020-06-15T16:09:38.727Z,1592237378.727 [DataOverHttps](INFO): SBD MOMSN=12390945
2020-06-15T16:09:54.700Z,1592237394.700 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200611T161745/Express0056.lzma
2020-06-15T16:09:55.703Z,1592237395.703 [DataOverHttps](INFO): Moved sent file to Logs/20200611T161745/Express0056.lzma.bak
2020-06-15T16:09:55.703Z,1592237395.703 [DataOverHttps](INFO): SBD MOMSN=12390949
2020-06-15T16:09:55.977Z,1592237395.977 [Startup:StartupSatComms:B](INFO): Timed out from 2020-06-15T16:08:55.8Z
2020-06-15T16:09:55.977Z,1592237395.977 [Startup:StartupSatComms:B] Stopped
2020-06-15T16:09:55.977Z,1592237395.977 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-06-15T16:09:55.977Z,1592237395.977 [Startup:StartupSatComms] Stopped
2020-06-15T16:09:55.977Z,1592237395.977 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-06-15T16:09:55.978Z,1592237395.978 [Startup](INFO): Completed Startup
2020-06-15T16:09:55.978Z,1592237395.978 [MissionManager](INFO): Startup is completed.
2020-06-15T16:09:55.978Z,1592237395.978 [MissionManager](INFO): Uninitializing Mission Startup
2020-06-15T16:09:55.978Z,1592237395.978 [Startup] Stopped
2020-06-15T16:09:55.978Z,1592237395.978 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-06-15T16:09:55.978Z,1592237395.978 [Startup:A.GoToSurface] Stopped
2020-06-15T16:09:55.978Z,1592237395.978 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-15T16:09:56.410Z,1592237396.410 [MissionManager](IMPORTANT): Started mission Default
2020-06-15T16:09:56.411Z,1592237396.411 [Default] Running Loop=1
2020-06-15T16:09:56.411Z,1592237396.411 [Default](DEBUG): Aggregate::initialize Default
2020-06-15T16:09:56.411Z,1592237396.411 [Default:B.GoToSurface] Running Loop=1
2020-06-15T16:09:56.411Z,1592237396.411 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-15T16:09:56.411Z,1592237396.411 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-15T16:09:56.411Z,1592237396.411 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-15T16:09:56.412Z,1592237396.412 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-15T16:09:56.412Z,1592237396.412 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-15T16:09:56.412Z,1592237396.412 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-15T16:09:56.418Z,1592237396.418 [Default:A.Wait] Running Loop=1
2020-06-15T16:09:56.418Z,1592237396.418 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:10:03.262Z,1592237403.262 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:10:03.262Z,1592237403.262 [DVL_micro] No Fault, FailCount= 3
2020-06-15T16:10:09.727Z,1592237409.727 [Default:A.Wait](INFO): Done Waiting.
2020-06-15T16:10:09.727Z,1592237409.727 [Default:A.Wait] Stopped
2020-06-15T16:10:09.727Z,1592237409.727 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:10:10.149Z,1592237410.149 [Default:CheckIn] Running Loop=1
2020-06-15T16:10:10.149Z,1592237410.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:10:10.150Z,1592237410.150 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:10:10.521Z,1592237410.521 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-06-15T16:10:20.986Z,1592237420.986 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:10:20.986Z,1592237420.986 [DVL_micro] Communications Fault, FailCount= 1
2020-06-15T16:10:20.986Z,1592237420.986 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:10:21.079Z,1592237421.079 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:10:21.461Z,1592237421.461 [DVL_micro](INFO): Powering down
2020-06-15T16:10:22.244Z,1592237422.244 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:10:22.244Z,1592237422.244 [DVL_micro] No Fault, FailCount= 1
2020-06-15T16:10:39.974Z,1592237439.974 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:10:39.974Z,1592237439.974 [DVL_micro] Communications Fault, FailCount= 2
2020-06-15T16:10:39.974Z,1592237439.974 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:10:40.006Z,1592237440.006 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:10:40.449Z,1592237440.449 [DVL_micro](INFO): Powering down
2020-06-15T16:10:41.227Z,1592237441.227 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:10:41.227Z,1592237441.227 [DVL_micro] No Fault, FailCount= 2
2020-06-15T16:10:58.962Z,1592237458.962 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:10:58.962Z,1592237458.962 [DVL_micro] Communications Fault, FailCount= 3
2020-06-15T16:10:58.962Z,1592237458.962 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:10:59.027Z,1592237459.027 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:10:59.027Z,1592237459.027 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-06-15T16:10:59.441Z,1592237459.441 [DVL_micro](INFO): Powering down
2020-06-15T16:10:59.493Z,1592237459.493 [CBIT](INFO): Critical error at 20200615T161059
2020-06-15T16:11:48.255Z,1592237508.255 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-15T16:12:08.470Z,1592237528.470 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-06-15T16:12:08.470Z,1592237528.470 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-15T16:12:08.494Z,1592237528.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-15T16:12:08.905Z,1592237528.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-15T16:12:08.905Z,1592237528.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-06-15T16:13:31.423Z,1592237611.423 [CommandLine](IMPORTANT): got command failComponent
2020-06-15T16:13:31.423Z,1592237611.423 [CommandLine](IMPORTANT): Failed components:
2020-06-15T16:13:31.424Z,1592237611.424 [CommandLine](IMPORTANT): DVL_micro: Communications Fault
2020-06-15T16:13:46.655Z,1592237626.655 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2020-06-15T16:13:46.656Z,1592237626.656 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2020-06-15T16:13:46.660Z,1592237626.660 [BPC1](INFO): Received data from all battery sticks.
2020-06-15T16:13:52.280Z,1592237632.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161335.00,A,3648.16628,N,12147.28619,W,0.272,195.01,150620,,,A*73
2020-06-15T16:13:52.283Z,1592237632.283 [NAL9602](INFO): GPS fix at 20200615T161335: (36.802771, -121.788103)
2020-06-15T16:13:52.370Z,1592237632.370 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T16:13:52.370Z,1592237632.370 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-15T16:13:52.730Z,1592237632.730 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-15T16:13:54.272Z,1592237634.272 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:13:54.374Z,1592237634.374 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:13:59.242Z,1592237639.242 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:13:59.242Z,1592237639.242 [DVL_micro] No Fault, FailCount= 3
2020-06-15T16:14:02.275Z,1592237642.275 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20200615T160556/Courier0004.lzma
2020-06-15T16:14:03.274Z,1592237643.274 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0004.lzma.bak
2020-06-15T16:14:03.275Z,1592237643.275 [DataOverHttps](INFO): SBD MOMSN=12390951
2020-06-15T16:14:05.344Z,1592237645.344 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.041894
CHAN A1 (24V): -0.189757
CHAN A2 (12V): 0.047819
CHAN A3 (5V): -0.635623
CHAN B0 (3.3V): 0.007628
CHAN B1 (3.15aV): 0.267513
CHAN B2 (3.15bV): 0.003885
CHAN B3 (GND): -0.007208
OPEN: 0.003272
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:14:16.925Z,1592237656.925 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:14:16.925Z,1592237656.925 [DVL_micro] Communications Fault, FailCount= 1
2020-06-15T16:14:16.926Z,1592237656.926 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:14:17.029Z,1592237657.029 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:14:17.401Z,1592237657.401 [DVL_micro](INFO): Powering down
2020-06-15T16:14:18.207Z,1592237658.207 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:14:18.207Z,1592237658.207 [DVL_micro] No Fault, FailCount= 1
2020-06-15T16:14:20.623Z,1592237660.623 [DataOverHttps](INFO): Sending 452 bytes from file Logs/20200611T185815/Express0001.lzma
2020-06-15T16:14:21.610Z,1592237661.610 [DataOverHttps](INFO): Moved sent file to Logs/20200611T185815/Express0001.lzma.bak
2020-06-15T16:14:21.610Z,1592237661.610 [DataOverHttps](INFO): SBD MOMSN=12390955
2020-06-15T16:14:24.200Z,1592237664.200 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-06-15T16:14:24.602Z,1592237664.602 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-15T16:14:30.887Z,1592237670.887 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:14:31.102Z,1592237671.102 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:14:35.914Z,1592237675.914 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-06-15T16:14:35.914Z,1592237675.914 [DVL_micro] Communications Fault, FailCount= 2
2020-06-15T16:14:35.914Z,1592237675.914 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:14:36.024Z,1592237676.024 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:14:36.393Z,1592237676.393 [DVL_micro](INFO): Powering down
2020-06-15T16:14:37.180Z,1592237677.180 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:14:37.180Z,1592237677.180 [DVL_micro] No Fault, FailCount= 2
2020-06-15T16:14:38.318Z,1592237678.318 [DataOverHttps](INFO): Sending 395 bytes from file Logs/20200611T191215/Express0001.lzma
2020-06-15T16:14:39.319Z,1592237679.319 [DataOverHttps](INFO): Moved sent file to Logs/20200611T191215/Express0001.lzma.bak
2020-06-15T16:14:39.319Z,1592237679.319 [DataOverHttps](INFO): SBD MOMSN=12390965
2020-06-15T16:14:42.008Z,1592237682.008 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.053371
CHAN A1 (24V): 0.085414
CHAN A2 (12V): 0.816533
CHAN A3 (5V): 0.013306
CHAN B0 (3.3V): 0.004024
CHAN B1 (3.15aV): 0.126200
CHAN B2 (3.15bV): 0.003358
CHAN B3 (GND): -0.008115
OPEN: -0.030572
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:14:52.704Z,1592237692.704 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:14:52.959Z,1592237692.959 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:14:53.306Z,1592237693.306 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro
2020-06-15T16:14:55.647Z,1592237695.647 [DataOverHttps](INFO): Sending 961 bytes from file Logs/20200615T160556/Express0001.lzma
2020-06-15T16:14:56.646Z,1592237696.646 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0001.lzma.bak
2020-06-15T16:14:56.647Z,1592237696.647 [DataOverHttps](INFO): SBD MOMSN=12390974
2020-06-15T16:15:03.837Z,1592237703.837 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.013201
CHAN A1 (24V): 0.069638
CHAN A2 (12V): 0.030167
CHAN A3 (5V): -0.206209
CHAN B0 (3.3V): 0.004413
CHAN B1 (3.15aV): 0.204563
CHAN B2 (3.15bV): 0.003432
CHAN B3 (GND): -0.006078
OPEN: 0.002171
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:15:13.209Z,1592237713.209 [DataOverHttps](INFO): Sending 413 bytes from file Logs/20200615T160556/Express0005.lzma
2020-06-15T16:15:14.210Z,1592237714.210 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0005.lzma.bak
2020-06-15T16:15:14.211Z,1592237714.211 [DataOverHttps](INFO): SBD MOMSN=12390999
2020-06-15T16:15:15.602Z,1592237715.602 [Default:CheckIn:Read_Iridium] Stopped
2020-06-15T16:15:15.602Z,1592237715.602 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-15T16:15:15.602Z,1592237715.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:15:21.791Z,1592237721.791 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:15:22.021Z,1592237722.021 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:15:25.219Z,1592237725.219 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:15:25.220Z,1592237725.220 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+16.9,0000.0,148

2020-06-15T16:15:32.936Z,1592237732.936 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.100830
CHAN A1 (24V): 0.071127
CHAN A2 (12V): 0.031207
CHAN A3 (5V): -0.049714
CHAN B0 (3.3V): 0.003605
CHAN B1 (3.15aV): 0.003208
CHAN B2 (3.15bV): 0.037759
CHAN B3 (GND): -0.006558
OPEN: 0.136135
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:16:48.447Z,1592237808.447 [DVL_micro](ERROR): Failed to parse:
:SA,-00.53,-04.55,022.3
2020-06-15T16:17:45.834Z,1592237865.834 [DataOverHttps](IMPORTANT): SBD MTMSN=20200615T161728
2020-06-15T16:17:53.233Z,1592237873.233 [DataOverHttps](INFO): Received command:gfscan
2020-06-15T16:17:53.255Z,1592237873.254 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:17:53.519Z,1592237873.519 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:18:04.437Z,1592237884.437 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039033
CHAN A1 (24V): 0.069279
CHAN A2 (12V): 0.030307
CHAN A3 (5V): 0.006360
CHAN B0 (3.3V): 0.003300
CHAN B1 (3.15aV): 0.003409
CHAN B2 (3.15bV): 0.003352
CHAN B3 (GND): -0.005477
OPEN: 0.985879
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:18:34.285Z,1592237914.285 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00
2020-06-15T16:19:02.165Z,1592237942.165 [DVL_micro](ERROR): Failed to parse:
:WI,+00000,+00000,+00000,+00000,I
2020-06-15T16:20:16.145Z,1592238016.145 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-15T16:20:16.146Z,1592238016.146 [Default:CheckIn:C.Wait] Stopped
2020-06-15T16:20:16.146Z,1592238016.146 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:20:16.146Z,1592238016.146 [Default:CheckIn:D] Running Loop=1
2020-06-15T16:20:16.527Z,1592238016.527 [Default:CheckIn:D] Stopped
2020-06-15T16:20:16.527Z,1592238016.527 [Default:CheckIn:E] Running Loop=1
2020-06-15T16:20:16.926Z,1592238016.926 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.335264 min 
2020-06-15T16:20:16.926Z,1592238016.926 [Default:CheckIn:E] Stopped
2020-06-15T16:20:16.926Z,1592238016.926 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-15T16:20:16.926Z,1592238016.926 [Default:CheckIn] Stopped
2020-06-15T16:20:16.927Z,1592238016.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T16:20:16.927Z,1592238016.927 [Default:CheckIn](INFO): Running loop #2
2020-06-15T16:20:16.927Z,1592238016.927 [Default:CheckIn] Running Loop=2
2020-06-15T16:20:16.927Z,1592238016.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:20:16.927Z,1592238016.927 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:20:18.919Z,1592238018.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162001.00,A,3648.15884,N,12147.28337,W,0.311,296.69,150620,,,A*7C
2020-06-15T16:20:18.922Z,1592238018.922 [NAL9602](INFO): GPS fix at 20200615T162001: (36.802647, -121.788056)
2020-06-15T16:20:18.946Z,1592238018.946 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T16:20:18.946Z,1592238018.946 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-15T16:20:25.404Z,1592238025.404 [DVL_micro](ERROR): Failed to parse:00.50,+0000.32,+0000.32,+0010.54
2020-06-15T16:20:26.248Z,1592238026.248 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200615T160556/Courier0007.lzma
2020-06-15T16:20:27.250Z,1592238027.250 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0007.lzma.bak
2020-06-15T16:20:27.251Z,1592238027.251 [DataOverHttps](INFO): SBD MOMSN=12391011
2020-06-15T16:20:43.314Z,1592238043.314 [DataOverHttps](INFO): Sending 847 bytes from file Logs/20200615T160556/Express0008.lzma
2020-06-15T16:20:44.314Z,1592238044.314 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0008.lzma.bak
2020-06-15T16:20:44.315Z,1592238044.315 [DataOverHttps](INFO): SBD MOMSN=12391014
2020-06-15T16:21:00.640Z,1592238060.640 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20200615T160556/Express0011.lzma
2020-06-15T16:21:01.642Z,1592238061.642 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0011.lzma.bak
2020-06-15T16:21:01.643Z,1592238061.643 [DataOverHttps](INFO): SBD MOMSN=12391036
2020-06-15T16:21:03.002Z,1592238063.002 [Default:CheckIn:Read_Iridium] Stopped
2020-06-15T16:21:03.002Z,1592238063.002 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-15T16:21:03.002Z,1592238063.002 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:22:11.236Z,1592238131.236 [DVL_micro](ERROR): Failed to parse:
:000000000,35.0,+16.8,0000.0,1489.0,000
2020-06-15T16:22:55.672Z,1592238175.672 [NAL9602](INFO): SBD MO Status=2, MOMSN=633, MT Status=2, MTMSN=0
2020-06-15T16:22:55.672Z,1592238175.672 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-15T16:23:06.598Z,1592238186.598 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:23:06.598Z,1592238186.598 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+16.81489.0,000
2020-06-15T16:23:32.846Z,1592238212.846 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:23:32.846Z,1592238212.846 [DVL_micro](ERROR): Failed to parse:
:BI,-00123,+00059,+00101,
2020-06-15T16:23:58.704Z,1592238238.704 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:23:58.704Z,1592238238.704 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.53,+0000.332,+0002.38
2020-06-15T16:24:00.716Z,1592238240.716 [DVL_micro](ERROR): Failed to parse:
:WI,+0000,+0000,I
2020-06-15T16:24:17.279Z,1592238257.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=633, MT Status=2, MTMSN=0
2020-06-15T16:24:17.279Z,1592238257.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-15T16:24:56.470Z,1592238296.470 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:24:56.471Z,1592238296.471 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.53,+0000.32,+0000.32,
2020-06-15T16:25:26.362Z,1592238326.362 [NAL9602](INFO): SBD MO Status=2, MOMSN=633, MT Status=2, MTMSN=0
2020-06-15T16:25:26.362Z,1592238326.362 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-15T16:25:26.770Z,1592238326.770 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-15T16:25:57.468Z,1592238357.468 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-15T16:26:03.571Z,1592238363.571 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-15T16:26:03.571Z,1592238363.571 [Default:CheckIn:C.Wait] Stopped
2020-06-15T16:26:03.571Z,1592238363.571 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:26:03.571Z,1592238363.571 [Default:CheckIn:D] Running Loop=1
2020-06-15T16:26:03.985Z,1592238363.985 [Default:CheckIn:D] Stopped
2020-06-15T16:26:03.986Z,1592238363.986 [Default:CheckIn:E] Running Loop=1
2020-06-15T16:26:04.374Z,1592238364.374 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.126245 min 
2020-06-15T16:26:04.374Z,1592238364.374 [Default:CheckIn:E] Stopped
2020-06-15T16:26:04.374Z,1592238364.374 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-15T16:26:04.375Z,1592238364.375 [Default:CheckIn] Stopped
2020-06-15T16:26:04.375Z,1592238364.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T16:26:04.375Z,1592238364.375 [Default:CheckIn](INFO): Running loop #3
2020-06-15T16:26:04.375Z,1592238364.375 [Default:CheckIn] Running Loop=3
2020-06-15T16:26:04.375Z,1592238364.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:26:04.375Z,1592238364.375 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:26:06.364Z,1592238366.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162549.00,A,3648.16223,N,12147.28052,W,0.214,318.84,150620,,,D*74
2020-06-15T16:26:06.374Z,1592238366.374 [NAL9602](INFO): GPS fix at 20200615T162549: (36.802704, -121.788009)
2020-06-15T16:26:06.398Z,1592238366.398 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T16:26:06.398Z,1592238366.398 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-15T16:26:13.952Z,1592238373.952 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200615T160556/Courier0013.lzma
2020-06-15T16:26:14.955Z,1592238374.955 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0013.lzma.bak
2020-06-15T16:26:14.955Z,1592238374.955 [DataOverHttps](INFO): SBD MOMSN=12391038
2020-06-15T16:26:18.080Z,1592238378.080 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:26:18.080Z,1592238378.080 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.53,+0000.34,+00010.52
2020-06-15T16:26:31.121Z,1592238391.121 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20200615T160556/Express0014.lzma
2020-06-15T16:26:32.122Z,1592238392.122 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0014.lzma.bak
2020-06-15T16:26:32.123Z,1592238392.123 [DataOverHttps](INFO): SBD MOMSN=12391041
2020-06-15T16:26:33.461Z,1592238393.461 [Default:CheckIn:Read_Iridium] Stopped
2020-06-15T16:26:33.461Z,1592238393.461 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-15T16:26:33.461Z,1592238393.461 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:26:43.933Z,1592238403.933 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00
2020-06-15T16:27:13.443Z,1592238433.443 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00
2020-06-15T16:27:39.753Z,1592238459.753 [DVL_micro](ERROR): DVL uart error: serial timeout
2020-06-15T16:27:39.753Z,1592238459.753 [DVL_micro] Communications Fault, FailCount= 1
2020-06-15T16:27:39.753Z,1592238459.753 [DVL_micro](ERROR): Communications Fault
2020-06-15T16:27:39.753Z,1592238459.753 [DVL_micro](ERROR): Failed to parse:
2020-06-15T16:27:39.838Z,1592238459.838 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-15T16:27:40.157Z,1592238460.157 [DVL_micro](INFO): Powering down
2020-06-15T16:27:40.936Z,1592238460.936 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-15T16:27:40.936Z,1592238460.936 [DVL_micro] No Fault, FailCount= 1
2020-06-15T16:28:26.951Z,1592238506.951 [DVL_micro](ERROR): Failed to parse:
:WI,+00000,+00000,+00000,+00000,I
2020-06-15T16:28:54.827Z,1592238534.827 [DVL_micro](ERROR): Failed to parse:
:0000000006.8,0000.0,1489.0,000
2020-06-15T16:30:49.282Z,1592238649.282 [DataOverHttps](IMPORTANT): SBD MTMSN=20200615T163031
2020-06-15T16:30:56.788Z,1592238656.788 [DataOverHttps](INFO): Received command:gfscan
2020-06-15T16:30:56.827Z,1592238656.827 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:30:56.891Z,1592238656.891 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:31:07.788Z,1592238667.788 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.046035
CHAN A1 (24V): 0.071002
CHAN A2 (12V): 0.034056
CHAN A3 (5V): 0.007479
CHAN B0 (3.3V): 0.004451
CHAN B1 (3.15aV): 0.003582
CHAN B2 (3.15bV): 0.003559
CHAN B3 (GND): -0.005949
OPEN: 0.008749
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:31:08.554Z,1592238668.554 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-15T16:31:34.033Z,1592238694.033 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-15T16:31:34.033Z,1592238694.033 [Default:CheckIn:C.Wait] Stopped
2020-06-15T16:31:34.033Z,1592238694.033 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:31:34.033Z,1592238694.033 [Default:CheckIn:D] Running Loop=1
2020-06-15T16:31:34.434Z,1592238694.434 [Default:CheckIn:D] Stopped
2020-06-15T16:31:34.434Z,1592238694.434 [Default:CheckIn:E] Running Loop=1
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.633722 min 
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn:E] Stopped
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn] Stopped
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn](INFO): Running loop #4
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn] Running Loop=4
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:31:34.845Z,1592238694.845 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:31:36.836Z,1592238696.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163119.00,A,3648.16004,N,12147.27896,W,0.233,331.24,150620,,,D*78
2020-06-15T16:31:36.838Z,1592238696.838 [NAL9602](INFO): GPS fix at 20200615T163119: (36.802667, -121.787983)
2020-06-15T16:31:36.862Z,1592238696.862 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T16:31:36.862Z,1592238696.862 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-15T16:31:38.048Z,1592238698.048 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:31:38.049Z,1592238698.049 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.53,+0000.34,+0000..40
2020-06-15T16:31:43.947Z,1592238703.947 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200615T160556/Courier0016.lzma
2020-06-15T16:31:44.946Z,1592238704.946 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0016.lzma.bak
2020-06-15T16:31:44.947Z,1592238704.947 [DataOverHttps](INFO): SBD MOMSN=12391047
2020-06-15T16:32:00.793Z,1592238720.793 [DataOverHttps](INFO): Sending 444 bytes from file Logs/20200615T160556/Express0017.lzma
2020-06-15T16:32:01.794Z,1592238721.794 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0017.lzma.bak
2020-06-15T16:32:01.795Z,1592238721.795 [DataOverHttps](INFO): SBD MOMSN=12391050
2020-06-15T16:32:03.140Z,1592238723.140 [Default:CheckIn:Read_Iridium] Stopped
2020-06-15T16:32:03.140Z,1592238723.140 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-15T16:32:03.140Z,1592238723.140 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:32:05.920Z,1592238725.920 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:32:05.920Z,1592238725.920 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+16.8,0000.0,1489.0,000
2020-06-15T16:32:08.744Z,1592238728.744 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-15T16:33:21.468Z,1592238801.468 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:33:21.468Z,1592238801.468 [DVL_micro](ERROR): Failed to parse:
:TS,000000035.0,+16.71489.0,0000000,+0000,+00000+00000,+0000,+0000,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00
2020-06-15T16:33:27.526Z,1592238807.526 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+16.8,0000.0,1489.0,000
2020-06-15T16:33:29.550Z,1592238809.550 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-15T16:33:29.550Z,1592238809.550 [DVL_micro](ERROR): Failed to parse:
:RD,+00000.50,+00010.18
2020-06-15T16:34:38.630Z,1592238878.630 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:34:38.630Z,1592238878.630 [DVL_micro](ERROR): Failed to parse:
:TS,000000035.0,+16.8,0000.0,1489.0,000
2020-06-15T16:35:42.469Z,1592238942.469 [DVL_micro](ERROR): Failed to parse:
:WI,-00176,-0400,+00000,A
2020-06-15T16:36:23.269Z,1592238983.269 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:36:23.269Z,1592238983.269 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+16.,1489.0,0-01663,+0313,+0000,-01663,+01520,+00313,+00000,I
2020-06-15T16:37:03.711Z,1592239023.711 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-15T16:37:03.711Z,1592239023.711 [Default:CheckIn:C.Wait] Stopped
2020-06-15T16:37:03.711Z,1592239023.711 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:37:03.712Z,1592239023.712 [Default:CheckIn:D] Running Loop=1
2020-06-15T16:37:04.101Z,1592239024.101 [Default:CheckIn:D] Stopped
2020-06-15T16:37:04.101Z,1592239024.101 [Default:CheckIn:E] Running Loop=1
2020-06-15T16:37:04.505Z,1592239024.505 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.128170 min 
2020-06-15T16:37:04.505Z,1592239024.505 [Default:CheckIn:E] Stopped
2020-06-15T16:37:04.506Z,1592239024.506 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-15T16:37:04.506Z,1592239024.506 [Default:CheckIn] Stopped
2020-06-15T16:37:04.506Z,1592239024.506 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T16:37:04.506Z,1592239024.506 [Default:CheckIn](INFO): Running loop #5
2020-06-15T16:37:04.506Z,1592239024.506 [Default:CheckIn] Running Loop=5
2020-06-15T16:37:04.506Z,1592239024.506 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:37:04.506Z,1592239024.506 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:37:06.501Z,1592239026.501 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:37:06.501Z,1592239026.501 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+16.8,009.0,000
2020-06-15T16:37:06.504Z,1592239026.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163649.00,A,3648.16661,N,12147.28141,W,0.039,296.15,150620,,,D*75
2020-06-15T16:37:06.506Z,1592239026.506 [NAL9602](INFO): GPS fix at 20200615T163649: (36.802777, -121.788023)
2020-06-15T16:37:06.571Z,1592239026.571 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T16:37:06.571Z,1592239026.571 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-15T16:37:13.930Z,1592239033.930 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200615T160556/Courier0019.lzma
2020-06-15T16:37:14.926Z,1592239034.926 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0019.lzma.bak
2020-06-15T16:37:14.927Z,1592239034.927 [DataOverHttps](INFO): SBD MOMSN=12391058
2020-06-15T16:37:31.061Z,1592239051.061 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20200615T160556/Express0021.lzma
2020-06-15T16:37:32.097Z,1592239052.097 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0021.lzma.bak
2020-06-15T16:37:32.097Z,1592239052.097 [DataOverHttps](INFO): SBD MOMSN=12391061
2020-06-15T16:37:33.590Z,1592239053.590 [Default:CheckIn:Read_Iridium] Stopped
2020-06-15T16:37:33.590Z,1592239053.590 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-15T16:37:33.590Z,1592239053.590 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:37:37.207Z,1592239057.207 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-06-15T16:37:37.289Z,1592239057.289 [NAL9602](FAULT): received: 
+CSQ:0

OK3, 2, 0, 0, 0

OK
2020-06-15T16:37:37.289Z,1592239057.289 [NAL9602] Data Fault, FailCount= 1
2020-06-15T16:37:37.289Z,1592239057.289 [NAL9602](ERROR): Data Fault
2020-06-15T16:37:37.389Z,1592239057.389 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-15T16:37:37.602Z,1592239057.602 [NAL9602](INFO): Powering down
2020-06-15T16:37:38.494Z,1592239058.494 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-15T16:37:38.494Z,1592239058.494 [NAL9602] No Fault, FailCount= 1
2020-06-15T16:38:05.077Z,1592239085.077 [DVL_micro](ERROR): Failed to parse:
:WI,-02858,+01429,-00827,+00000,A
2020-06-15T16:38:07.906Z,1592239087.906 [NAL9602](INFO): Powering up NAL9602
2020-06-15T16:38:12.757Z,1592239092.757 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:38:12.757Z,1592239092.757 [DVL_micro](ERROR): Failed to parse:
:BI,+00441,-02332,+00921,+

2020-06-15T16:38:18.818Z,1592239098.818 [NAL9602](INFO): NAL9602 initialized
2020-06-15T16:38:49.918Z,1592239129.918 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-15T16:39:16.596Z,1592239156.596 [DVL_micro](ERROR): Failed to parse:
:WI,+01477,+01646,+00478,+00000,A
2020-06-15T16:39:47.310Z,1592239187.310 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-15T16:39:47.310Z,1592239187.310 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999..99
2020-06-15T16:40:53.544Z,1592239253.544 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:40:53.545Z,1592239253.545 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.8,0000.0,1489.WI,+01384,+00089,+00066,+00000,A
2020-06-15T16:41:59.801Z,1592239319.801 [DVL_micro](ERROR): Failed to parse:
:WI,+00238,-0163,+00000,00238,-01691,+00123,+00000,I
2020-06-15T16:42:34.173Z,1592239354.173 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-15T16:42:34.173Z,1592239354.173 [Default:CheckIn:C.Wait] Stopped
2020-06-15T16:42:34.173Z,1592239354.173 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:42:34.173Z,1592239354.173 [Default:CheckIn:D] Running Loop=1
2020-06-15T16:42:34.575Z,1592239354.575 [Default:CheckIn:D] Stopped
2020-06-15T16:42:34.575Z,1592239354.575 [Default:CheckIn:E] Running Loop=1
2020-06-15T16:42:34.973Z,1592239354.973 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.636064 min 
2020-06-15T16:42:34.973Z,1592239354.973 [Default:CheckIn:E] Stopped
2020-06-15T16:42:34.973Z,1592239354.973 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-15T16:42:34.973Z,1592239354.973 [Default:CheckIn] Stopped
2020-06-15T16:42:34.973Z,1592239354.973 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T16:42:34.974Z,1592239354.974 [Default:CheckIn](INFO): Running loop #6
2020-06-15T16:42:34.974Z,1592239354.974 [Default:CheckIn] Running Loop=6
2020-06-15T16:42:34.974Z,1592239354.974 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:42:34.974Z,1592239354.974 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:42:36.967Z,1592239356.967 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164218.00,A,3648.16450,N,12147.28521,W,0.233,335.18,150620,,,A*78
2020-06-15T16:42:36.970Z,1592239356.970 [NAL9602](INFO): GPS fix at 20200615T164218: (36.802742, -121.788087)
2020-06-15T16:42:36.994Z,1592239356.994 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T16:42:36.994Z,1592239356.994 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-15T16:42:39.388Z,1592239359.388 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-15T16:42:44.576Z,1592239364.576 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200615T160556/Courier0025.lzma
2020-06-15T16:42:45.578Z,1592239365.578 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0025.lzma.bak
2020-06-15T16:42:45.579Z,1592239365.579 [DataOverHttps](INFO): SBD MOMSN=12391064
2020-06-15T16:43:01.649Z,1592239381.649 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20200615T160556/Express0026.lzma
2020-06-15T16:43:02.650Z,1592239382.650 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0026.lzma.bak
2020-06-15T16:43:02.651Z,1592239382.651 [DataOverHttps](INFO): SBD MOMSN=12391067
2020-06-15T16:43:04.073Z,1592239384.073 [Default:CheckIn:Read_Iridium] Stopped
2020-06-15T16:43:04.073Z,1592239384.073 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-15T16:43:04.073Z,1592239384.073 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:43:10.098Z,1592239390.098 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-15T16:43:34.067Z,1592239414.067 [CommandLine](IMPORTANT): got command strobe off
2020-06-15T16:43:34.067Z,1592239414.067 [CommandLine](IMPORTANT): Deactivating strobe
2020-06-15T16:44:04.637Z,1592239444.637 [DVL_micro](ERROR): Failed to parse:
:BI,-01338,-00213,+00203,+00000,I
2020-06-15T16:45:56.481Z,1592239556.481 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-15T16:45:58.496Z,1592239558.496 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.262035
2020-06-15T16:46:09.490Z,1592239569.490 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:46:09.490Z,1592239569.490 [DVL_micro](ERROR): Failed to parse:
:BI,00130,+00089,+00000,I
2020-06-15T16:46:31.633Z,1592239591.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-15T16:47:06.781Z,1592239626.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-15T16:47:08.056Z,1592239628.056 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:47:08.056Z,1592239628.056 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.9,0000.0000
2020-06-15T16:47:41.921Z,1592239661.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-15T16:47:56.463Z,1592239676.463 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003457
2020-06-15T16:48:04.633Z,1592239684.633 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-15T16:48:04.633Z,1592239684.633 [Default:CheckIn:C.Wait] Stopped
2020-06-15T16:48:04.633Z,1592239684.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:48:04.634Z,1592239684.634 [Default:CheckIn:D] Running Loop=1
2020-06-15T16:48:05.045Z,1592239685.045 [Default:CheckIn:D] Stopped
2020-06-15T16:48:05.045Z,1592239685.045 [Default:CheckIn:E] Running Loop=1
2020-06-15T16:48:05.452Z,1592239685.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.143905 min 
2020-06-15T16:48:05.452Z,1592239685.452 [Default:CheckIn:E] Stopped
2020-06-15T16:48:05.452Z,1592239685.452 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-15T16:48:05.452Z,1592239685.452 [Default:CheckIn] Stopped
2020-06-15T16:48:05.453Z,1592239685.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T16:48:05.453Z,1592239685.453 [Default:CheckIn](INFO): Running loop #7
2020-06-15T16:48:05.453Z,1592239685.453 [Default:CheckIn] Running Loop=7
2020-06-15T16:48:05.453Z,1592239685.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:48:05.453Z,1592239685.453 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:48:06.642Z,1592239686.642 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-15T16:48:06.642Z,1592239686.642 [DVL_micro](ERROR): Failed to parse:
:BI,+0085,+0077,I
2020-06-15T16:48:07.541Z,1592239687.541 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:48:07.916Z,1592239687.916 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:48:18.810Z,1592239698.810 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.096098
CHAN A1 (24V): 0.115330
CHAN A2 (12V): 0.038785
CHAN A3 (5V): 0.009725
CHAN B0 (3.3V): 0.004077
CHAN B1 (3.15aV): 0.004024
CHAN B2 (3.15bV): 0.003013
CHAN B3 (GND): -0.009162
OPEN: 0.006397
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:48:28.790Z,1592239708.790 [CommandLine](IMPORTANT): got command gfscan
2020-06-15T16:48:28.905Z,1592239708.905 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-15T16:48:39.799Z,1592239719.799 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.093678
CHAN A1 (24V): 0.106287
CHAN A2 (12V): 0.042857
CHAN A3 (5V): 0.010611
CHAN B0 (3.3V): 0.004806
CHAN B1 (3.15aV): 0.004209
CHAN B2 (3.15bV): 0.004462
CHAN B3 (GND): -0.007723
OPEN: 0.005327
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-15T16:49:05.218Z,1592239745.218 [DVL_micro](ERROR): Failed to parse:
:WI,-01402,-01388,+00623,+00000-01402,-0133,+00000,0000000000000.00,+000,9999.99,000.00
2020-06-15T16:49:40.764Z,1592239780.764 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:49:40.764Z,1592239780.764 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+1.0,1489.0,,-01135,-0517,+00000,A
2020-06-15T16:50:36.922Z,1592239836.922 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:50:36.922Z,1592239836.922 [DVL_micro](ERROR): Failed to parse:
:BI,-01243,-01553,+00648I
2020-06-15T16:52:00.957Z,1592239920.957 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:52:00.957Z,1592239920.957 [DVL_micro](ERROR): Failed to parse:
:TS,000000,39,0000.0,1489.WI,-01202,-01542,+00608,+00000,A
2020-06-15T16:53:05.631Z,1592239985.631 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-15T16:48:05.5Z
2020-06-15T16:53:05.631Z,1592239985.631 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T16:53:05.631Z,1592239985.631 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-15T16:53:09.643Z,1592239989.643 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-15T16:53:12.796Z,1592239992.796 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200615T160556/Courier0028.lzma
2020-06-15T16:53:13.799Z,1592239993.799 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Courier0028.lzma.bak
2020-06-15T16:53:13.799Z,1592239993.799 [DataOverHttps](INFO): SBD MOMSN=12391071
2020-06-15T16:53:29.038Z,1592240009.038 [DataOverHttps](INFO): Sending 495 bytes from file Logs/20200615T160556/Express0029.lzma
2020-06-15T16:53:30.039Z,1592240010.039 [DataOverHttps](INFO): Moved sent file to Logs/20200615T160556/Express0029.lzma.bak
2020-06-15T16:53:30.039Z,1592240010.039 [DataOverHttps](INFO): SBD MOMSN=12391073
2020-06-15T16:53:31.483Z,1592240011.483 [Default:CheckIn:Read_Iridium] Stopped
2020-06-15T16:53:31.483Z,1592240011.483 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-15T16:53:31.483Z,1592240011.483 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-15T16:53:40.346Z,1592240020.346 [DVL_micro](ERROR): Failed to parse:
:WI,+00393,+00036,+01151,+00000,A
2020-06-15T16:53:40.346Z,1592240020.346 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-15T16:53:48.013Z,1592240028.013 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000999.99,000.00
2020-06-15T16:54:28.816Z,1592240068.816 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:54:28.816Z,1592240068.816 [DVL_micro](ERROR): Failed to parse:
:BI,-00028,-0031,+00000,I
2020-06-15T16:55:01.964Z,1592240101.964 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:55:01.964Z,1592240101.964 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,.8,0000.0,1489.0,000
2020-06-15T16:55:19.723Z,1592240119.723 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:55:19.723Z,1592240119.723 [DVL_micro](ERROR): Failed to parse:
:BI,-01083,-01540,+00355,+00000+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-15T16:55:35.076Z,1592240135.076 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:55:35.076Z,1592240135.076 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+17.7,0000.0,1489.0,000
2020-06-15T16:56:23.577Z,1592240183.577 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T16:56:23.577Z,1592240183.577 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.6,0000.0,1489.0,,+00496,+00081,-00281,+00000,A
2020-06-15T16:57:50.429Z,1592240270.429 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:57:50.429Z,1592240270.429 [DVL_micro](ERROR): Failed to parse:
:BI,-0002,-00291,+0:BD,+00000000.00,+00000000.00,+00000000.00,900.00
2020-06-15T16:58:32.071Z,1592240312.071 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-15T16:58:32.071Z,1592240312.071 [Default:CheckIn:C.Wait] Stopped
2020-06-15T16:58:32.071Z,1592240312.071 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-15T16:58:32.071Z,1592240312.071 [Default:CheckIn:D] Running Loop=1
2020-06-15T16:58:32.470Z,1592240312.470 [Default:CheckIn:D] Stopped
2020-06-15T16:58:32.470Z,1592240312.470 [Default:CheckIn:E] Running Loop=1
2020-06-15T16:58:32.885Z,1592240312.885 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.600989 min 
2020-06-15T16:58:32.885Z,1592240312.885 [Default:CheckIn:E] Stopped
2020-06-15T16:58:32.886Z,1592240312.886 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-15T16:58:32.886Z,1592240312.886 [Default:CheckIn] Stopped
2020-06-15T16:58:32.886Z,1592240312.886 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T16:58:32.886Z,1592240312.886 [Default:CheckIn](INFO): Running loop #8
2020-06-15T16:58:32.886Z,1592240312.886 [Default:CheckIn] Running Loop=8
2020-06-15T16:58:32.886Z,1592240312.886 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-15T16:58:32.886Z,1592240312.886 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-15T16:58:34.466Z,1592240314.466 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-15T16:58:34.466Z,1592240314.466 [NAL9602] Data Fault, FailCount= 1
2020-06-15T16:58:34.466Z,1592240314.466 [NAL9602](ERROR): Data Fault
2020-06-15T16:58:34.500Z,1592240314.500 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-15T16:58:34.875Z,1592240314.875 [NAL9602](INFO): Powering down
2020-06-15T16:58:35.726Z,1592240315.726 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-15T16:58:35.726Z,1592240315.726 [NAL9602] No Fault, FailCount= 1
2020-06-15T16:58:46.587Z,1592240326.587 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T16:58:46.587Z,1592240326.587 [DVL_micro](ERROR): Failed to parse:
:BI,+00090,-00229,-0017,I
2020-06-15T16:59:05.164Z,1592240345.164 [NAL9602](INFO): Powering up NAL9602
2020-06-15T16:59:16.074Z,1592240356.074 [NAL9602](INFO): NAL9602 initialized
2020-06-15T16:59:35.069Z,1592240375.069 [DVL_micro](ERROR): Failed to parse:
:WI,+00206,+00026,-00211,+00000,A
2020-06-15T17:00:38.894Z,1592240438.894 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T17:00:38.894Z,1592240438.894 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.6,089.0,000
2020-06-15T17:01:45.160Z,1592240505.160 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-15T17:01:45.160Z,1592240505.160 [DVL_micro](ERROR): Failed to parse:
:BI,+00292,+00209,-00338,+

2020-06-15T17:02:18.288Z,1592240538.288 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T17:02:18.288Z,1592240538.288 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.7,0000.0,00
2020-06-15T17:02:28.510Z,1592240548.510 [CommandLine](IMPORTANT): got command quit
2020-06-15T17:02:29.517Z,1592240549.517 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:29.517Z,1592240549.517 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:29.682Z,1592240549.682 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-06-15T17:02:29.683Z,1592240549.683 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:29.683Z,1592240549.683 [CommandLine](INFO): Join timeout helper Thread ID is 3279
2020-06-15T17:02:29.693Z,1592240549.693 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-06-15T17:02:29.693Z,1592240549.693 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:29.693Z,1592240549.693 [NavChartDb](INFO): Join timeout helper Thread ID is 3280
2020-06-15T17:02:29.937Z,1592240549.937 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:29.937Z,1592240549.937 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:29.945Z,1592240549.945 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-06-15T17:02:29.945Z,1592240549.945 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:29.945Z,1592240549.945 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3281
2020-06-15T17:02:30.397Z,1592240550.397 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:30.398Z,1592240550.398 [WetLabsBB2FL](INFO): Powering down
2020-06-15T17:02:30.398Z,1592240550.398 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:30.399Z,1592240550.399 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-06-15T17:02:30.399Z,1592240550.399 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:30.399Z,1592240550.399 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3282
2020-06-15T17:02:30.489Z,1592240550.489 [CTD_Seabird](INFO): Powering down
2020-06-15T17:02:30.501Z,1592240550.501 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:30.501Z,1592240550.501 [CTD_Seabird](INFO): Powering down
2020-06-15T17:02:30.513Z,1592240550.513 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:30.525Z,1592240550.525 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-06-15T17:02:30.525Z,1592240550.525 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:30.525Z,1592240550.525 [Radio_Surface](INFO): Join timeout helper Thread ID is 3283
2020-06-15T17:02:30.585Z,1592240550.585 [Radio_Surface](INFO): Powering down
2020-06-15T17:02:30.586Z,1592240550.586 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:30.586Z,1592240550.586 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:30.598Z,1592240550.598 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-06-15T17:02:30.598Z,1592240550.598 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:30.598Z,1592240550.598 [DataOverHttps](INFO): Join timeout helper Thread ID is 3284
2020-06-15T17:02:31.212Z,1592240551.212 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-15T17:02:31.212Z,1592240551.212 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.7,0000.,000
2020-06-15T17:02:31.625Z,1592240551.625 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:31.639Z,1592240551.639 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:31.650Z,1592240551.650 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-06-15T17:02:31.650Z,1592240551.650 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:31.650Z,1592240551.650 [logger](INFO): Join timeout helper Thread ID is 3285
2020-06-15T17:02:31.691Z,1592240551.691 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:31.692Z,1592240551.692 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:31.697Z,1592240551.697 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-06-15T17:02:31.697Z,1592240551.697 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:31.698Z,1592240551.698 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-06-15T17:02:31.698Z,1592240551.698 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:31.698Z,1592240551.698 [controlThread](INFO): Join timeout helper Thread ID is 3286
2020-06-15T17:02:32.001Z,1592240552.001 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-15T17:02:32.001Z,1592240552.001 [controlThread](DEBUG): Uninitializing ControlThread
2020-06-15T17:02:32.001Z,1592240552.001 [AHRS_M2](INFO): Powering down
2020-06-15T17:02:32.145Z,1592240552.145 [DVL_micro](INFO): Powering down
2020-06-15T17:02:32.146Z,1592240552.146 [NAL9602](INFO): Powering down
2020-06-15T17:02:32.166Z,1592240552.166 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-06-15T17:02:32.167Z,1592240552.167 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-06-15T17:02:32.168Z,1592240552.168 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-06-15T17:02:32.168Z,1592240552.168 [MissionManager](INFO): Uninitializing Mission Default
2020-06-15T17:02:32.168Z,1592240552.168 [Default] Stopped
2020-06-15T17:02:32.169Z,1592240552.169 [Default](DEBUG): Aggregate::uninitialize Default
2020-06-15T17:02:32.169Z,1592240552.169 [Default:B.GoToSurface] Stopped
2020-06-15T17:02:32.169Z,1592240552.169 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-15T17:02:32.169Z,1592240552.169 [Default:CheckIn] Stopped
2020-06-15T17:02:32.169Z,1592240552.169 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-15T17:02:32.169Z,1592240552.169 [Default:CheckIn:Read_GPS] Stopped
2020-06-15T17:02:32.171Z,1592240552.171 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-06-15T17:02:32.172Z,1592240552.172 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-06-15T17:02:32.172Z,1592240552.172 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-06-15T17:02:32.172Z,1592240552.172 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-06-15T17:02:32.173Z,1592240552.173 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-06-15T17:02:32.173Z,1592240552.173 [BuoyancyServo](INFO): Powering down
2020-06-15T17:02:32.185Z,1592240552.185 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-06-15T17:02:32.185Z,1592240552.185 [ElevatorServo](INFO): Powering down
2020-06-15T17:02:32.186Z,1592240552.186 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-06-15T17:02:32.186Z,1592240552.186 [MassServo](INFO): Powering down
2020-06-15T17:02:32.187Z,1592240552.187 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-15T17:02:32.187Z,1592240552.187 [RudderServo](INFO): Powering down
2020-06-15T17:02:32.188Z,1592240552.188 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-06-15T17:02:32.188Z,1592240552.188 [ThrusterServo](INFO): Powering down
2020-06-15T17:02:32.189Z,1592240552.189 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-06-15T17:02:32.189Z,1592240552.189 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-06-15T17:02:32.189Z,1592240552.189 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-06-15T17:02:32.189Z,1592240552.189 [CBIT](DEBUG): Powering off loads.
2020-06-15T17:02:32.201Z,1592240552.201 [CBIT](DEBUG): Disabling WDT.
2020-06-15T17:02:32.213Z,1592240552.213 [CBIT](DEBUG): Opening all GF detection circuits.
2020-06-15T17:02:32.213Z,1592240552.213 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:32.288Z,1592240552.288 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:32.296Z,1592240552.296 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:32.356Z,1592240552.356 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:32.361Z,1592240552.361 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:32.386Z,1592240552.386 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-15T17:02:32.462Z,1592240552.462 [logger ThreadHandler](INFO): Thread cancelled.