2019-10-02T15:40:51.380Z,1570030851.380 [Supervisor](DEBUG): Initializing supervisor.
2019-10-02T15:40:51.383Z,1570030851.383 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-02T15:40:51.384Z,1570030851.384 [SyncHandler](INFO): Protected caller Thread ID is 8866
2019-10-02T15:40:51.384Z,1570030851.384 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-02T15:40:51.385Z,1570030851.385 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-02T15:40:51.385Z,1570030851.385 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8867
2019-10-02T15:40:51.388Z,1570030851.388 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-02T15:40:51.398Z,1570030851.398 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-02T15:40:51.399Z,1570030851.399 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-02T15:40:51.400Z,1570030851.400 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8868
2019-10-02T15:40:51.400Z,1570030851.400 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-02T15:40:51.401Z,1570030851.401 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-02T15:40:51.402Z,1570030851.402 [logger ThreadHandler](INFO): Protected caller Thread ID is 8869
2019-10-02T15:40:51.404Z,1570030851.404 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-02T15:40:51.404Z,1570030851.404 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-02T15:40:51.406Z,1570030851.406 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-02T15:40:51.504Z,1570030851.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-02T15:40:51.505Z,1570030851.505 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-02T15:40:52.065Z,1570030852.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-02T15:40:52.065Z,1570030852.065 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-02T15:40:52.166Z,1570030852.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-02T15:40:52.167Z,1570030852.167 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-02T15:40:52.271Z,1570030852.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-02T15:40:52.271Z,1570030852.271 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-02T15:40:52.355Z,1570030852.355 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-02T15:40:52.495Z,1570030852.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-02T15:40:52.495Z,1570030852.495 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-02T15:40:52.791Z,1570030852.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-02T15:40:52.792Z,1570030852.792 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-02T15:40:53.241Z,1570030853.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-02T15:40:53.241Z,1570030853.241 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-02T15:40:53.388Z,1570030853.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-02T15:40:53.388Z,1570030853.388 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-02T15:40:53.580Z,1570030853.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-02T15:40:53.580Z,1570030853.580 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-02T15:40:54.023Z,1570030854.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-02T15:40:54.023Z,1570030854.023 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-02T15:40:54.238Z,1570030854.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-02T15:40:54.239Z,1570030854.239 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-02T15:40:54.443Z,1570030854.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-02T15:40:54.444Z,1570030854.444 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-02T15:40:54.844Z,1570030854.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-02T15:40:54.844Z,1570030854.844 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-02T15:40:55.170Z,1570030855.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-02T15:40:55.172Z,1570030855.172 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-10-02T15:40:55.173Z,1570030855.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-10-02T15:40:55.260Z,1570030855.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-10-02T15:40:55.416Z,1570030855.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-10-02T15:40:55.545Z,1570030855.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-10-02T15:40:56.288Z,1570030856.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-10-02T15:40:56.455Z,1570030856.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-10-02T15:40:56.773Z,1570030856.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-10-02T15:40:56.999Z,1570030856.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-02T15:40:57.000Z,1570030857.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-10-02T15:40:57.098Z,1570030857.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-10-02T15:40:57.200Z,1570030857.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-10-02T15:40:57.343Z,1570030857.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-10-02T15:40:57.447Z,1570030857.447 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-10-02T15:40:57.447Z,1570030857.447 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-02T15:40:57.460Z,1570030857.460 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-02T15:40:57.506Z,1570030857.506 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-02T15:40:57.613Z,1570030857.613 [VerticalControl] Loaded
2019-10-02T15:40:57.614Z,1570030857.614 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-02T15:40:57.614Z,1570030857.614 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-02T15:40:57.680Z,1570030857.680 [HorizontalControl] Loaded
2019-10-02T15:40:57.681Z,1570030857.681 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-02T15:40:57.681Z,1570030857.681 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-02T15:40:57.687Z,1570030857.687 [SpeedControl] Loaded
2019-10-02T15:40:57.687Z,1570030857.687 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-02T15:40:57.687Z,1570030857.687 [LoopControl](DEBUG): Construct LoopControl.
2019-10-02T15:40:57.688Z,1570030857.688 [LoopControl] Loaded
2019-10-02T15:40:57.688Z,1570030857.688 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-02T15:40:57.689Z,1570030857.689 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-02T15:40:57.689Z,1570030857.689 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-02T15:40:57.727Z,1570030857.727 [DepthRateCalculator] Loaded
2019-10-02T15:40:57.728Z,1570030857.728 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-02T15:40:57.733Z,1570030857.733 [PitchRateCalculator] Loaded
2019-10-02T15:40:57.733Z,1570030857.733 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-02T15:40:57.748Z,1570030857.748 [SpeedCalculator] Loaded
2019-10-02T15:40:57.748Z,1570030857.748 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-02T15:40:57.768Z,1570030857.768 [TempGradientCalculator] Loaded
2019-10-02T15:40:57.769Z,1570030857.769 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-02T15:40:57.773Z,1570030857.773 [YawRateCalculator] Loaded
2019-10-02T15:40:57.774Z,1570030857.774 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-02T15:40:57.802Z,1570030857.802 [ElevatorOffsetCalculator] Loaded
2019-10-02T15:40:57.802Z,1570030857.802 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-02T15:40:57.803Z,1570030857.803 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-02T15:40:57.803Z,1570030857.803 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-02T15:40:57.834Z,1570030857.834 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-02T15:40:57.835Z,1570030857.835 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-02T15:40:57.901Z,1570030857.901 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-02T15:40:57.901Z,1570030857.901 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-02T15:40:58.179Z,1570030858.179 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-02T15:40:58.179Z,1570030858.179 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-02T15:40:58.271Z,1570030858.271 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-02T15:40:58.272Z,1570030858.272 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-02T15:40:58.565Z,1570030858.565 [DataOverHttps] Loaded
2019-10-02T15:40:58.566Z,1570030858.566 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-02T15:40:58.567Z,1570030858.567 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2019-10-02T15:40:58.567Z,1570030858.567 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8948
2019-10-02T15:40:58.603Z,1570030858.603 [Depth_Keller] Loaded
2019-10-02T15:40:58.604Z,1570030858.604 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-02T15:40:58.698Z,1570030858.698 [NAL9602] Loaded
2019-10-02T15:40:58.698Z,1570030858.698 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-02T15:40:58.714Z,1570030858.714 [Onboard] Loaded
2019-10-02T15:40:58.714Z,1570030858.714 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-02T15:40:58.717Z,1570030858.717 [Radio_Surface] Loaded
2019-10-02T15:40:58.717Z,1570030858.717 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-02T15:40:58.718Z,1570030858.718 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2019-10-02T15:40:58.719Z,1570030858.719 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8949
2019-10-02T15:40:58.836Z,1570030858.836 [DAT] Loaded
2019-10-02T15:40:58.836Z,1570030858.836 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-02T15:41:00.280Z,1570030860.280 [BPC1] Loaded
2019-10-02T15:41:00.281Z,1570030860.281 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-02T15:41:00.281Z,1570030860.281 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-02T15:41:00.282Z,1570030860.282 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-02T15:41:00.380Z,1570030860.380 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-02T15:41:00.380Z,1570030860.380 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-02T15:41:00.400Z,1570030860.400 [NavChart] Loaded
2019-10-02T15:41:00.401Z,1570030860.401 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-02T15:41:00.405Z,1570030860.405 [UniversalFixResidualReporter] Loaded
2019-10-02T15:41:00.405Z,1570030860.405 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-02T15:41:00.405Z,1570030860.405 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-02T15:41:00.406Z,1570030860.406 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-02T15:41:00.531Z,1570030860.531 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-02T15:41:00.542Z,1570030860.542 [SBIT] Loaded
2019-10-02T15:41:00.542Z,1570030860.542 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-02T15:41:00.543Z,1570030860.543 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-02T15:41:00.554Z,1570030860.554 [IBIT] Loaded
2019-10-02T15:41:00.554Z,1570030860.554 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-02T15:41:00.557Z,1570030860.557 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-02T15:41:00.691Z,1570030860.691 [CBIT] Loaded
2019-10-02T15:41:00.691Z,1570030860.691 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-02T15:41:00.691Z,1570030860.691 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-02T15:41:00.692Z,1570030860.692 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-02T15:41:00.864Z,1570030860.864 [BuoyancyServo] Loaded
2019-10-02T15:41:00.865Z,1570030860.865 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-02T15:41:00.956Z,1570030860.956 [ElevatorServo] Loaded
2019-10-02T15:41:00.956Z,1570030860.956 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-02T15:41:00.978Z,1570030860.978 [MassServo] Loaded
2019-10-02T15:41:00.979Z,1570030860.979 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-02T15:41:01.004Z,1570030861.004 [RudderServo] Loaded
2019-10-02T15:41:01.004Z,1570030861.004 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-02T15:41:01.014Z,1570030861.014 [ThrusterServo] Loaded
2019-10-02T15:41:01.014Z,1570030861.014 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-02T15:41:01.015Z,1570030861.015 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-02T15:41:01.015Z,1570030861.015 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-02T15:41:01.132Z,1570030861.132 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-02T15:41:01.133Z,1570030861.133 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-02T15:41:01.360Z,1570030861.360 [BackSeatDriver] Loaded
2019-10-02T15:41:01.360Z,1570030861.360 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2019-10-02T15:41:01.362Z,1570030861.362 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A6B4E0
2019-10-02T15:41:01.362Z,1570030861.362 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 8950
2019-10-02T15:41:01.371Z,1570030861.371 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-02T15:41:01.376Z,1570030861.376 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-02T15:41:01.377Z,1570030861.377 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-02T15:41:01.382Z,1570030861.382 [CTD_Seabird](INFO): created writer for : depth
2019-10-02T15:41:01.382Z,1570030861.382 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-02T15:41:01.387Z,1570030861.387 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-02T15:41:01.387Z,1570030861.387 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-02T15:41:01.393Z,1570030861.393 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-02T15:41:01.393Z,1570030861.393 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-02T15:41:01.398Z,1570030861.398 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-02T15:41:01.399Z,1570030861.399 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-02T15:41:01.404Z,1570030861.404 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-02T15:41:01.404Z,1570030861.404 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-02T15:41:01.409Z,1570030861.409 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-02T15:41:01.435Z,1570030861.435 [CTD_Seabird] Loaded
2019-10-02T15:41:01.435Z,1570030861.435 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-02T15:41:01.436Z,1570030861.436 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2019-10-02T15:41:01.437Z,1570030861.437 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8951
2019-10-02T15:41:01.451Z,1570030861.451 [PAR_Licor] Loaded
2019-10-02T15:41:01.451Z,1570030861.451 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-02T15:41:01.457Z,1570030861.457 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2019-10-02T15:41:01.457Z,1570030861.457 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2019-10-02T15:41:01.462Z,1570030861.462 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2019-10-02T15:41:01.462Z,1570030861.462 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2019-10-02T15:41:01.466Z,1570030861.466 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2019-10-02T15:41:01.466Z,1570030861.466 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2019-10-02T15:41:01.470Z,1570030861.470 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2019-10-02T15:41:01.471Z,1570030861.471 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2019-10-02T15:41:01.475Z,1570030861.475 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2019-10-02T15:41:01.475Z,1570030861.475 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2019-10-02T15:41:01.479Z,1570030861.479 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2019-10-02T15:41:01.479Z,1570030861.479 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2019-10-02T15:41:01.483Z,1570030861.483 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2019-10-02T15:41:01.484Z,1570030861.484 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2019-10-02T15:41:01.488Z,1570030861.488 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.492Z,1570030861.492 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.492Z,1570030861.492 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.493Z,1570030861.493 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.497Z,1570030861.497 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.497Z,1570030861.497 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.501Z,1570030861.501 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.501Z,1570030861.501 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-02T15:41:01.506Z,1570030861.506 [WetLabsBB2FL] Loaded
2019-10-02T15:41:01.506Z,1570030861.506 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-02T15:41:01.507Z,1570030861.507 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0
2019-10-02T15:41:01.507Z,1570030861.507 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8952
2019-10-02T15:41:01.523Z,1570030861.523 [WetLabsUBAT..SerialDriver](INFO): Created logger
2019-10-02T15:41:01.524Z,1570030861.524 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2019-10-02T15:41:01.524Z,1570030861.524 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2019-10-02T15:41:01.529Z,1570030861.529 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -nopump -ldir /mnt/mmc/LRAUV/Logs/latest
2019-10-02T15:41:01.529Z,1570030861.529 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2019-10-02T15:41:01.529Z,1570030861.529 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2019-10-02T15:41:01.533Z,1570030861.533 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2019-10-02T15:41:01.534Z,1570030861.534 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2019-10-02T15:41:01.538Z,1570030861.538 [WetLabsUBAT] Loaded
2019-10-02T15:41:01.538Z,1570030861.538 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2019-10-02T15:41:01.539Z,1570030861.539 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0
2019-10-02T15:41:01.539Z,1570030861.539 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 8953
2019-10-02T15:41:01.540Z,1570030861.540 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-02T15:41:01.543Z,1570030861.543 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-02T15:41:01.544Z,1570030861.544 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-02T15:41:01.551Z,1570030861.551 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-02T15:41:01.552Z,1570030861.552 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B2B4E0
2019-10-02T15:41:01.552Z,1570030861.552 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8954
2019-10-02T15:41:01.557Z,1570030861.557 [Supervisor](INFO): Main Thread ID is 8457
2019-10-02T15:41:01.557Z,1570030861.557 [Supervisor](DEBUG): Running supervisor.
2019-10-02T15:41:01.557Z,1570030861.557 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8955
2019-10-02T15:41:01.562Z,1570030861.562 [controlThread ThreadHandler](INFO): Handler Thread ID is 8956
2019-10-02T15:41:01.563Z,1570030861.563 [controlThread](DEBUG): Initializing ControlThread
2019-10-02T15:41:01.564Z,1570030861.564 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-02T15:41:01.565Z,1570030861.565 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-02T15:41:01.566Z,1570030861.566 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-02T15:41:01.566Z,1570030861.566 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-02T15:41:01.567Z,1570030861.567 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-02T15:41:01.567Z,1570030861.567 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-02T15:41:01.567Z,1570030861.567 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-02T15:41:01.568Z,1570030861.568 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-02T15:41:01.568Z,1570030861.568 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-02T15:41:01.568Z,1570030861.568 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-02T15:41:01.574Z,1570030861.574 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-02T15:41:01.575Z,1570030861.575 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-02T15:41:01.575Z,1570030861.575 [SBIT](INFO): Initialize SBIT Component.
2019-10-02T15:41:01.575Z,1570030861.575 [SBIT](IMPORTANT): git: 2019-09-23_TRN-6-g1f8b43a
2019-10-02T15:41:01.576Z,1570030861.576 [SBIT](INFO): git hash: 1f8b43ac03d7e40104dadce62697ccb9592aa06e
2019-10-02T15:41:01.576Z,1570030861.576 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-02T15:41:01.576Z,1570030861.576 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-02T15:41:01.578Z,1570030861.578 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-10-02T15:41:01.578Z,1570030861.578 [IBIT](INFO): Initialize IBIT Component.
2019-10-02T15:41:01.579Z,1570030861.579 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-02T15:41:01.580Z,1570030861.580 [logger ThreadHandler](INFO): Handler Thread ID is 8957
2019-10-02T15:41:01.593Z,1570030861.593 [CBIT](DEBUG): Initialized mux pins.
2019-10-02T15:41:01.593Z,1570030861.593 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-02T15:41:01.597Z,1570030861.597 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8958
2019-10-02T15:41:01.598Z,1570030861.598 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-02T15:41:01.605Z,1570030861.605 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8959
2019-10-02T15:41:01.613Z,1570030861.613 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 8960
2019-10-02T15:41:01.617Z,1570030861.617 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-02T15:41:01.617Z,1570030861.617 [CBIT](DEBUG): Initializing heartbeat.
2019-10-02T15:41:01.625Z,1570030861.625 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8961
2019-10-02T15:41:01.626Z,1570030861.626 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-02T15:41:01.629Z,1570030861.629 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-02T15:41:01.631Z,1570030861.631 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8963
2019-10-02T15:41:01.632Z,1570030861.632 [WetLabsBB2FL](INFO): Powering down
2019-10-02T15:41:01.665Z,1570030861.665 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 8964
2019-10-02T15:41:01.677Z,1570030861.677 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8965
2019-10-02T15:41:01.683Z,1570030861.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-02T15:41:01.683Z,1570030861.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-02T15:41:01.683Z,1570030861.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-02T15:41:01.683Z,1570030861.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-02T15:41:01.684Z,1570030861.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-02T15:41:01.684Z,1570030861.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-02T15:41:01.684Z,1570030861.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-02T15:41:01.684Z,1570030861.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-02T15:41:01.684Z,1570030861.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-02T15:41:01.685Z,1570030861.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-02T15:41:01.685Z,1570030861.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-02T15:41:01.685Z,1570030861.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-02T15:41:01.685Z,1570030861.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-02T15:41:01.685Z,1570030861.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-02T15:41:01.686Z,1570030861.686 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-02T15:41:01.686Z,1570030861.686 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-02T15:41:01.689Z,1570030861.689 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-02T15:41:01.689Z,1570030861.689 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-02T15:41:01.725Z,1570030861.725 [CBIT](DEBUG): Backplane powered.
2019-10-02T15:41:01.730Z,1570030861.730 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-02T15:41:01.753Z,1570030861.753 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-02T15:41:01.784Z,1570030861.784 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-10-02T15:41:01.785Z,1570030861.785 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-02T15:41:01.846Z,1570030861.846 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-02T15:41:01.847Z,1570030861.847 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-02T15:41:01.862Z,1570030861.862 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-02T15:41:01.900Z,1570030861.900 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-02T15:41:01.903Z,1570030861.903 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-02T15:41:01.932Z,1570030861.932 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-02T15:41:01.935Z,1570030861.935 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-10-02T15:41:01.952Z,1570030861.952 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-02T15:41:01.958Z,1570030861.958 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2019-10-02T15:41:01.978Z,1570030861.978 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-02T15:41:01.979Z,1570030861.979 [DAT](INFO): Powering up
2019-10-02T15:41:01.979Z,1570030861.979 [DAT](DEBUG): Initializing DAT.
2019-10-02T15:41:02.009Z,1570030862.009 [Radio_Surface](INFO): Powering up
2019-10-02T15:41:02.050Z,1570030862.050 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-02T15:41:02.065Z,1570030862.065 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-02T15:41:02.106Z,1570030862.106 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-02T15:41:02.113Z,1570030862.113 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-02T15:41:02.114Z,1570030862.114 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-02T15:41:02.134Z,1570030862.134 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-02T15:41:02.135Z,1570030862.135 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-02T15:41:02.145Z,1570030862.145 [MassServo](DEBUG): Initializing MassServo.
2019-10-02T15:41:02.146Z,1570030862.146 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-02T15:41:02.165Z,1570030862.165 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-02T15:41:02.166Z,1570030862.166 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-02T15:41:02.177Z,1570030862.177 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-02T15:41:02.402Z,1570030862.402 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-02T15:41:03.141Z,1570030863.141 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-02T15:41:03.141Z,1570030863.141 [RudderServo](FAULT): Rudder failed to initialize
2019-10-02T15:41:03.141Z,1570030863.141 [RudderServo] Communications Fault, FailCount= 1
2019-10-02T15:41:03.141Z,1570030863.141 [RudderServo](ERROR): Communications Fault
2019-10-02T15:41:03.252Z,1570030863.252 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-02T15:41:03.454Z,1570030863.454 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-02T15:41:03.454Z,1570030863.454 [RudderServo](INFO): Powering down
2019-10-02T15:41:04.136Z,1570030864.136 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-02T15:41:04.270Z,1570030864.270 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-02T15:41:04.274Z,1570030864.274 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-02T15:41:04.274Z,1570030864.274 [RudderServo] No Fault, FailCount= 1
2019-10-02T15:41:16.237Z,1570030876.237 [DAT](INFO): setting local address to 4
2019-10-02T15:41:16.630Z,1570030876.630 [DAT](INFO): set local address to 4
2019-10-02T15:41:28.736Z,1570030888.736 [NAL9602](INFO): Powering up NAL9602
2019-10-02T15:41:39.638Z,1570030899.638 [NAL9602](INFO): NAL9602 initialized
2019-10-02T15:41:40.481Z,1570030900.481 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:42:01.090Z,1570030921.090 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-02T15:42:01.094Z,1570030921.094 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:42:01.455Z,1570030921.455 [NAL9602](INFO): SBD MO Status=2, MOMSN=1882, MT Status=2, MTMSN=0
2019-10-02T15:42:01.456Z,1570030921.456 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-02T15:42:12.160Z,1570030932.160 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.041934
CHAN A1 (24V): 0.096273
CHAN A2 (12V): 0.037426
CHAN A3 (5V): 0.014140
CHAN B0 (3.3V): 0.010457
CHAN B1 (3.15aV): 0.010321
CHAN B2 (3.15bV): 0.010624
CHAN B3 (GND): 0.001451
OPEN: 0.009049
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:42:18.615Z,1570030938.615 [NAL9602](INFO): SBD MO Status=2, MOMSN=1882, MT Status=2, MTMSN=0
2019-10-02T15:42:18.616Z,1570030938.616 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-02T15:42:32.348Z,1570030952.348 [NAL9602](INFO): SBD MO Status=2, MOMSN=1882, MT Status=2, MTMSN=0
2019-10-02T15:42:32.348Z,1570030952.348 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-02T15:42:40.030Z,1570030960.030 [NAL9602](INFO): SBD MO Status=0, MOMSN=1882, MT Status=0, MTMSN=0
2019-10-02T15:42:40.030Z,1570030960.030 [NAL9602](INFO): No messages in MT queue
2019-10-02T15:42:40.845Z,1570030960.845 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:42:43.761Z,1570030963.761 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:42:46.985Z,1570030966.985 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:42:49.813Z,1570030969.813 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:42:53.053Z,1570030973.053 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:42:55.091Z,1570030975.091 [SBIT](IMPORTANT): SBIT PASSED
2019-10-02T15:42:55.139Z,1570030975.139 [CommandLine](IMPORTANT): got command configSet list
2019-10-02T15:42:55.139Z,1570030975.139 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): BackSeatDriver.verbosity=1 count;

2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere;

2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): Express none RDI_Pathfinder.height_above_sea_floor;

2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter;

2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): Express none WetLabsUBAT.biolum_potential;

2019-10-02T15:42:55.140Z,1570030975.140 [CommandLine](IMPORTANT): Express none WetLabsUBAT.flow_rate;

2019-10-02T15:42:55.141Z,1570030975.141 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;

2019-10-02T15:42:55.141Z,1570030975.141 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsUBAT.biolum_potential 1000000.000000 none;

2019-10-02T15:42:55.141Z,1570030975.141 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-10-02T15:42:55.141Z,1570030975.141 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2019-10-02T15:42:55.141Z,1570030975.141 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=80 cubic_centimeter;

2019-10-02T15:42:55.141Z,1570030975.141 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;

2019-10-02T15:42:55.479Z,1570030975.479 [MissionManager](IMPORTANT): Started mission Startup
2019-10-02T15:42:55.479Z,1570030975.479 [Startup] Running Loop=1
2019-10-02T15:42:55.479Z,1570030975.479 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-02T15:42:55.479Z,1570030975.479 [Startup:A.GoToSurface] Running Loop=1
2019-10-02T15:42:55.479Z,1570030975.479 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-02T15:42:55.480Z,1570030975.480 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-02T15:42:55.480Z,1570030975.480 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-02T15:42:55.481Z,1570030975.481 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-02T15:42:55.481Z,1570030975.481 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-02T15:42:55.482Z,1570030975.482 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-02T15:42:55.483Z,1570030975.483 [Startup:StartupSatComms] Running Loop=1
2019-10-02T15:42:55.483Z,1570030975.483 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-02T15:42:55.483Z,1570030975.483 [Startup:StartupSatComms:A] Running Loop=1
2019-10-02T15:42:55.881Z,1570030975.881 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:42:55.907Z,1570030975.907 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-02T15:42:59.109Z,1570030979.109 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:01.941Z,1570030981.941 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:02.023Z,1570030982.023 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:43:02.023Z,1570030982.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-02T15:43:02.023Z,1570030982.023 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:43:02.042Z,1570030982.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:43:02.387Z,1570030982.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:43:02.387Z,1570030982.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-02T15:43:05.169Z,1570030985.169 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:07.997Z,1570030987.997 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:11.233Z,1570030991.233 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:14.057Z,1570030994.057 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:17.285Z,1570030997.285 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:20.117Z,1570031000.117 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:22.941Z,1570031002.941 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:25.773Z,1570031005.773 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:29.005Z,1570031009.005 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:31.833Z,1570031011.833 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:35.065Z,1570031015.065 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:37.893Z,1570031017.893 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:41.121Z,1570031021.121 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:43.949Z,1570031023.949 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:46.785Z,1570031026.785 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:50.013Z,1570031030.013 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:52.841Z,1570031032.841 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:55.686Z,1570031035.686 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-02T15:42:55.5Z
2019-10-02T15:43:55.687Z,1570031035.687 [Startup:StartupSatComms:A] Stopped
2019-10-02T15:43:55.687Z,1570031035.687 [Startup:StartupSatComms:B] Running Loop=1
2019-10-02T15:43:56.076Z,1570031036.076 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:43:56.103Z,1570031036.103 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-02T15:44:06.173Z,1570031046.173 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:44:10.213Z,1570031050.213 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:44:14.657Z,1570031054.657 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:44:15.048Z,1570031055.048 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154403.00,A,3648.16862,N,12147.28431,W,0.214,95.14,021019,,,A*41
2019-10-02T15:44:15.051Z,1570031055.051 [NAL9602](INFO): GPS fix at 20191002T154403: (36.802810, -121.788072)
2019-10-02T15:44:26.254Z,1570031066.254 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004598
2019-10-02T15:44:38.232Z,1570031078.232 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191002T142540/Courier0028.lzma
2019-10-02T15:44:40.238Z,1570031080.238 [DataOverHttps](INFO): Moved sent file to Logs/20191002T142540/Courier0028.lzma.bak
2019-10-02T15:44:40.239Z,1570031080.239 [DataOverHttps](INFO): SBD MOMSN=11840194
2019-10-02T15:44:56.122Z,1570031096.122 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-02T15:43:55.7Z
2019-10-02T15:44:56.122Z,1570031096.122 [Startup:StartupSatComms:B] Stopped
2019-10-02T15:44:56.122Z,1570031096.122 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-02T15:44:56.122Z,1570031096.122 [Startup:StartupSatComms] Stopped
2019-10-02T15:44:56.123Z,1570031096.123 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-02T15:44:56.123Z,1570031096.123 [Startup](INFO): Completed Startup
2019-10-02T15:44:56.123Z,1570031096.123 [MissionManager](INFO): Startup is completed.
2019-10-02T15:44:56.124Z,1570031096.124 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-02T15:44:56.124Z,1570031096.124 [Startup] Stopped
2019-10-02T15:44:56.124Z,1570031096.124 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-02T15:44:56.124Z,1570031096.124 [Startup:A.GoToSurface] Stopped
2019-10-02T15:44:56.124Z,1570031096.124 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-02T15:44:56.521Z,1570031096.521 [MissionManager](IMPORTANT): Started mission Default
2019-10-02T15:44:56.521Z,1570031096.521 [Default] Running Loop=1
2019-10-02T15:44:56.521Z,1570031096.521 [Default](DEBUG): Aggregate::initialize Default
2019-10-02T15:44:56.521Z,1570031096.521 [Default:B.GoToSurface] Running Loop=1
2019-10-02T15:44:56.521Z,1570031096.521 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-02T15:44:56.522Z,1570031096.522 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-02T15:44:56.522Z,1570031096.522 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-02T15:44:56.522Z,1570031096.522 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-02T15:44:56.523Z,1570031096.523 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-02T15:44:56.523Z,1570031096.523 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-02T15:44:56.523Z,1570031096.523 [Default:A.Wait] Running Loop=1
2019-10-02T15:44:56.523Z,1570031096.523 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-02T15:44:56.754Z,1570031096.754 [DataOverHttps](INFO): Sending 581 bytes from file Logs/20191002T142540/Express0029.lzma
2019-10-02T15:44:58.450Z,1570031098.450 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-02T15:44:58.758Z,1570031098.758 [DataOverHttps](INFO): Moved sent file to Logs/20191002T142540/Express0029.lzma.bak
2019-10-02T15:44:58.758Z,1570031098.758 [DataOverHttps](INFO): SBD MOMSN=11840196
2019-10-02T15:45:02.929Z,1570031102.929 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:45:02.929Z,1570031102.929 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-02T15:45:02.929Z,1570031102.929 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:45:02.948Z,1570031102.948 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:45:03.366Z,1570031103.366 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:45:03.366Z,1570031103.366 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-02T15:45:09.844Z,1570031109.844 [Default:A.Wait](INFO): Done Waiting.
2019-10-02T15:45:09.844Z,1570031109.844 [Default:A.Wait] Stopped
2019-10-02T15:45:09.844Z,1570031109.844 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-02T15:45:10.204Z,1570031110.204 [Default:CheckIn] Running Loop=1
2019-10-02T15:45:10.204Z,1570031110.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-02T15:45:10.204Z,1570031110.204 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-02T15:45:10.634Z,1570031110.634 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-02T15:45:11.789Z,1570031111.789 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:45:12.183Z,1570031112.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154500.00,A,3648.16717,N,12147.28365,W,0.019,95.14,021019,,,A*47
2019-10-02T15:45:12.186Z,1570031112.186 [NAL9602](INFO): GPS fix at 20191002T154500: (36.802786, -121.788061)
2019-10-02T15:45:12.229Z,1570031112.229 [Default:CheckIn:Read_GPS] Stopped
2019-10-02T15:45:12.229Z,1570031112.229 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-02T15:45:12.629Z,1570031112.629 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-02T15:45:19.421Z,1570031119.421 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20191002T154051/Courier0004.lzma
2019-10-02T15:45:21.426Z,1570031121.426 [DataOverHttps](INFO): Moved sent file to Logs/20191002T154051/Courier0004.lzma.bak
2019-10-02T15:45:21.426Z,1570031121.426 [DataOverHttps](INFO): SBD MOMSN=11840211
2019-10-02T15:45:38.471Z,1570031138.471 [DataOverHttps](INFO): Sending 1107 bytes from file Logs/20191002T154051/Express0001.lzma
2019-10-02T15:45:40.475Z,1570031140.475 [DataOverHttps](INFO): Moved sent file to Logs/20191002T154051/Express0001.lzma.bak
2019-10-02T15:45:40.476Z,1570031140.476 [DataOverHttps](INFO): SBD MOMSN=11840215
2019-10-02T15:45:59.553Z,1570031159.553 [DataOverHttps](INFO): Sending 303 bytes from file Logs/20191002T154051/Express0005.lzma
2019-10-02T15:46:01.558Z,1570031161.558 [DataOverHttps](INFO): Moved sent file to Logs/20191002T154051/Express0005.lzma.bak
2019-10-02T15:46:01.558Z,1570031161.558 [DataOverHttps](INFO): SBD MOMSN=11840245
2019-10-02T15:46:03.191Z,1570031163.191 [Default:CheckIn:Read_Iridium] Stopped
2019-10-02T15:46:03.191Z,1570031163.191 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-02T15:46:03.191Z,1570031163.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-02T15:46:08.061Z,1570031168.061 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:46:08.465Z,1570031168.465 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:46:19.340Z,1570031179.340 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039318
CHAN A1 (24V): 0.092212
CHAN A2 (12V): 0.035646
CHAN A3 (5V): 0.013266
CHAN B0 (3.3V): 0.010760
CHAN B1 (3.15aV): 0.009001
CHAN B2 (3.15bV): 0.008635
CHAN B3 (GND): 0.001560
OPEN: 0.008679
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:46:30.912Z,1570031190.912 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB4
2019-10-02T15:46:34.103Z,1570031194.103 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:46:34.311Z,1570031194.311 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:46:45.207Z,1570031205.207 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039980
CHAN A1 (24V): 0.092419
CHAN A2 (12V): 0.035452
CHAN A3 (5V): 0.013561
CHAN B0 (3.3V): 0.009672
CHAN B1 (3.15aV): 0.009744
CHAN B2 (3.15bV): 0.009878
CHAN B3 (GND): 0.001282
OPEN: 0.008589
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:47:03.758Z,1570031223.758 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:47:03.758Z,1570031223.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-02T15:47:03.758Z,1570031223.758 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:47:03.769Z,1570031223.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:47:04.190Z,1570031224.190 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:47:04.190Z,1570031224.190 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-02T15:47:07.389Z,1570031227.389 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB2
2019-10-02T15:47:12.505Z,1570031232.505 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:47:12.674Z,1570031232.674 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:47:23.583Z,1570031243.583 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.038916
CHAN A1 (24V): -0.132589
CHAN A2 (12V): 0.033724
CHAN A3 (5V): 0.011612
CHAN B0 (3.3V): 0.009398
CHAN B1 (3.15aV): 0.008944
CHAN B2 (3.15bV): 0.008876
CHAN B3 (GND): 0.001689
OPEN: 0.008775
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:48:04.775Z,1570031284.775 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-10-02T15:48:18.116Z,1570031298.116 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-10-02T15:48:18.120Z,1570031298.120 [BPC1](INFO): Received data from all battery sticks.
2019-10-02T15:48:24.337Z,1570031304.337 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-02T15:48:29.464Z,1570031309.464 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:48:29.866Z,1570031309.866 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:48:40.757Z,1570031320.757 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039152
CHAN A1 (24V): 0.090652
CHAN A2 (12V): 0.035874
CHAN A3 (5V): 0.012833
CHAN B0 (3.3V): 0.009246
CHAN B1 (3.15aV): 0.009553
CHAN B2 (3.15bV): 0.009936
CHAN B3 (GND): 0.001562
OPEN: 0.008389
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:48:47.869Z,1570031327.869 [CommandLine](INFO): End of History
2019-10-02T15:48:47.888Z,1570031327.888 [CommandLine](INFO): End of History
2019-10-02T15:48:47.890Z,1570031327.890 [CommandLine](INFO): End of History
2019-10-02T15:49:01.343Z,1570031341.343 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB3
2019-10-02T15:49:04.976Z,1570031344.976 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:49:04.976Z,1570031344.976 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-02T15:49:04.976Z,1570031344.976 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:49:04.996Z,1570031344.996 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:49:05.417Z,1570031345.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:49:05.417Z,1570031345.417 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-02T15:49:06.231Z,1570031346.231 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:49:06.611Z,1570031346.611 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:49:17.507Z,1570031357.507 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039429
CHAN A1 (24V): 0.089519
CHAN A2 (12V): 0.034725
CHAN A3 (5V): 0.012527
CHAN B0 (3.3V): 0.009971
CHAN B1 (3.15aV): 0.009654
CHAN B2 (3.15bV): 0.009566
CHAN B3 (GND): 0.000630
OPEN: 0.007627
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:49:23.856Z,1570031363.856 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB4
2019-10-02T15:49:26.519Z,1570031366.519 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:49:26.810Z,1570031366.810 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:49:37.712Z,1570031377.712 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039379
CHAN A1 (24V): 0.089747
CHAN A2 (12V): 0.034437
CHAN A3 (5V): 0.012627
CHAN B0 (3.3V): 0.010690
CHAN B1 (3.15aV): 0.009107
CHAN B2 (3.15bV): 0.010080
CHAN B3 (GND): 0.001245
OPEN: 0.007240
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:49:48.303Z,1570031388.303 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC6
2019-10-02T15:49:50.096Z,1570031390.096 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:49:50.236Z,1570031390.236 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:50:01.189Z,1570031401.189 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039696
CHAN A1 (24V): 0.089672
CHAN A2 (12V): 0.035006
CHAN A3 (5V): 0.013267
CHAN B0 (3.3V): 0.010020
CHAN B1 (3.15aV): 0.009118
CHAN B2 (3.15bV): 0.009200
CHAN B3 (GND): 0.001409
OPEN: 0.007213
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:50:05.135Z,1570031405.135 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC2
2019-10-02T15:50:07.238Z,1570031407.238 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:50:07.611Z,1570031407.611 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:50:14.838Z,1570031414.838 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-02T15:50:18.524Z,1570031418.524 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.039567
CHAN A1 (24V): 0.090518
CHAN A2 (12V): 0.034866
CHAN A3 (5V): 0.012639
CHAN B0 (3.3V): 0.009775
CHAN B1 (3.15aV): 0.009035
CHAN B2 (3.15bV): 0.009759
CHAN B3 (GND): 0.001362
OPEN: 0.007151
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:50:29.950Z,1570031429.950 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC1
2019-10-02T15:50:33.479Z,1570031433.479 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:50:33.915Z,1570031433.915 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:50:44.798Z,1570031444.798 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.019058
CHAN A1 (24V): -0.000703
CHAN A2 (12V): -0.007738
CHAN A3 (5V): -0.001642
CHAN B0 (3.3V): 0.000441
CHAN B1 (3.15aV): -0.000179
CHAN B2 (3.15bV): 0.000052
CHAN B3 (GND): 0.002145
OPEN: 0.003146
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:50:45.550Z,1570031445.550 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-02T15:50:50.759Z,1570031450.759 [CommandLine](IMPORTANT): got command gfscan
2019-10-02T15:50:50.882Z,1570031450.882 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-02T15:51:01.752Z,1570031461.752 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.018962
CHAN A1 (24V): -0.000109
CHAN A2 (12V): -0.006579
CHAN A3 (5V): -0.002555
CHAN B0 (3.3V): -0.000283
CHAN B1 (3.15aV): -0.000102
CHAN B2 (3.15bV): -0.000111
CHAN B3 (GND): 0.002368
OPEN: 0.003205
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-02T15:51:03.751Z,1570031463.751 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-02T15:51:03.751Z,1570031463.751 [Default:CheckIn:C.Wait] Stopped
2019-10-02T15:51:03.751Z,1570031463.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-02T15:51:03.751Z,1570031463.751 [Default:CheckIn:D] Running Loop=1
2019-10-02T15:51:04.155Z,1570031464.155 [Default:CheckIn:D] Stopped
2019-10-02T15:51:04.155Z,1570031464.155 [Default:CheckIn:E] Running Loop=1
2019-10-02T15:51:04.559Z,1570031464.559 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.127231 min 
2019-10-02T15:51:04.559Z,1570031464.559 [Default:CheckIn:E] Stopped
2019-10-02T15:51:04.560Z,1570031464.560 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-02T15:51:04.560Z,1570031464.560 [Default:CheckIn] Stopped
2019-10-02T15:51:04.560Z,1570031464.560 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-02T15:51:04.560Z,1570031464.560 [Default:CheckIn](INFO): Running loop #2
2019-10-02T15:51:04.560Z,1570031464.560 [Default:CheckIn] Running Loop=2
2019-10-02T15:51:04.560Z,1570031464.560 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-02T15:51:04.560Z,1570031464.560 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-02T15:51:05.789Z,1570031465.789 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:51:05.789Z,1570031465.789 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-02T15:51:05.789Z,1570031465.789 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:51:05.838Z,1570031465.838 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:51:06.157Z,1570031466.157 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:51:06.197Z,1570031466.197 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:51:06.197Z,1570031466.197 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-02T15:51:06.552Z,1570031466.552 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155055.00,A,3648.16243,N,12147.28411,W,0.272,152.93,021019,,,A*79
2019-10-02T15:51:06.554Z,1570031466.554 [NAL9602](INFO): GPS fix at 20191002T155055: (36.802707, -121.788068)
2019-10-02T15:51:06.578Z,1570031466.578 [Default:CheckIn:Read_GPS] Stopped
2019-10-02T15:51:06.578Z,1570031466.578 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-02T15:51:13.797Z,1570031473.797 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20191002T154051/Courier0007.lzma
2019-10-02T15:51:15.802Z,1570031475.802 [DataOverHttps](INFO): Moved sent file to Logs/20191002T154051/Courier0007.lzma.bak
2019-10-02T15:51:15.802Z,1570031475.802 [DataOverHttps](INFO): SBD MOMSN=11840258
2019-10-02T15:51:32.667Z,1570031492.667 [DataOverHttps](INFO): Sending 1036 bytes from file Logs/20191002T154051/Express0008.lzma
2019-10-02T15:51:34.670Z,1570031494.670 [DataOverHttps](INFO): Moved sent file to Logs/20191002T154051/Express0008.lzma.bak
2019-10-02T15:51:34.671Z,1570031494.671 [DataOverHttps](INFO): SBD MOMSN=11840261
2019-10-02T15:51:36.484Z,1570031496.484 [Default:CheckIn:Read_Iridium] Stopped
2019-10-02T15:51:36.484Z,1570031496.484 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-02T15:51:36.484Z,1570031496.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-02T15:51:37.254Z,1570031497.254 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-10-02T15:51:37.329Z,1570031497.329 [NAL9602](FAULT): received: 
+CSQ:0

OK82, 0, 0, 0, 0

OK
2019-10-02T15:51:37.329Z,1570031497.329 [NAL9602] Data Fault, FailCount= 1
2019-10-02T15:51:37.329Z,1570031497.329 [NAL9602](ERROR): Data Fault
2019-10-02T15:51:37.368Z,1570031497.368 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-02T15:51:37.658Z,1570031497.658 [NAL9602](INFO): Powering down
2019-10-02T15:51:38.515Z,1570031498.515 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-02T15:51:38.515Z,1570031498.515 [NAL9602] No Fault, FailCount= 1
2019-10-02T15:52:07.963Z,1570031527.963 [NAL9602](INFO): Powering up NAL9602
2019-10-02T15:52:18.886Z,1570031538.886 [NAL9602](INFO): NAL9602 initialized
2019-10-02T15:52:49.974Z,1570031569.974 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-02T15:53:06.966Z,1570031586.966 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:53:06.966Z,1570031586.966 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-02T15:53:06.966Z,1570031586.966 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:53:06.977Z,1570031586.977 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:53:07.381Z,1570031587.381 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:53:07.381Z,1570031587.381 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-02T15:55:08.171Z,1570031708.171 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:55:08.171Z,1570031708.171 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-02T15:55:08.171Z,1570031708.171 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:55:08.181Z,1570031708.181 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:55:08.582Z,1570031708.582 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:55:08.582Z,1570031708.582 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-02T15:56:37.051Z,1570031797.051 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-02T15:56:37.051Z,1570031797.051 [Default:CheckIn:C.Wait] Stopped
2019-10-02T15:56:37.051Z,1570031797.051 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-02T15:56:37.051Z,1570031797.051 [Default:CheckIn:D] Running Loop=1
2019-10-02T15:56:37.455Z,1570031797.455 [Default:CheckIn:D] Stopped
2019-10-02T15:56:37.455Z,1570031797.455 [Default:CheckIn:E] Running Loop=1
2019-10-02T15:56:37.859Z,1570031797.859 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.682231 min 
2019-10-02T15:56:37.859Z,1570031797.859 [Default:CheckIn:E] Stopped
2019-10-02T15:56:37.859Z,1570031797.859 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-02T15:56:37.859Z,1570031797.859 [Default:CheckIn] Stopped
2019-10-02T15:56:37.860Z,1570031797.860 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-02T15:56:37.860Z,1570031797.860 [Default:CheckIn](INFO): Running loop #3
2019-10-02T15:56:37.860Z,1570031797.860 [Default:CheckIn] Running Loop=3
2019-10-02T15:56:37.860Z,1570031797.860 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-02T15:56:37.860Z,1570031797.860 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-02T15:56:39.465Z,1570031799.465 [NAL9602](DEBUG): Fix Requested
2019-10-02T15:56:39.855Z,1570031799.855 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155628.00,A,3648.16586,N,12147.27908,W,0.311,0.00,021019,,,A*79
2019-10-02T15:56:39.858Z,1570031799.858 [NAL9602](INFO): GPS fix at 20191002T155628: (36.802764, -121.787985)
2019-10-02T15:56:39.898Z,1570031799.898 [Default:CheckIn:Read_GPS] Stopped
2019-10-02T15:56:39.898Z,1570031799.898 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-02T15:56:41.481Z,1570031801.481 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-02T15:56:47.156Z,1570031807.156 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191002T154051/Courier0010.lzma
2019-10-02T15:56:49.162Z,1570031809.162 [DataOverHttps](INFO): Moved sent file to Logs/20191002T154051/Courier0010.lzma.bak
2019-10-02T15:56:49.162Z,1570031809.162 [DataOverHttps](INFO): SBD MOMSN=11840314
2019-10-02T15:57:05.573Z,1570031825.573 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20191002T154051/Express0011.lzma
2019-10-02T15:57:07.578Z,1570031827.578 [DataOverHttps](INFO): Moved sent file to Logs/20191002T154051/Express0011.lzma.bak
2019-10-02T15:57:07.578Z,1570031827.578 [DataOverHttps](INFO): SBD MOMSN=11840317
2019-10-02T15:57:09.369Z,1570031829.369 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:57:09.369Z,1570031829.369 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-02T15:57:09.369Z,1570031829.369 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:57:09.371Z,1570031829.371 [Default:CheckIn:Read_Iridium] Stopped
2019-10-02T15:57:09.371Z,1570031829.371 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-02T15:57:09.371Z,1570031829.371 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-02T15:57:09.380Z,1570031829.380 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:57:09.781Z,1570031829.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:57:09.781Z,1570031829.781 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-02T15:57:12.177Z,1570031832.177 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-02T15:59:10.273Z,1570031950.273 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T15:59:10.273Z,1570031950.273 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-02T15:59:10.273Z,1570031950.273 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T15:59:10.295Z,1570031950.295 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T15:59:10.583Z,1570031950.583 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T15:59:10.583Z,1570031950.583 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-02T16:01:11.368Z,1570032071.368 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-02T16:01:11.368Z,1570032071.368 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-02T16:01:11.368Z,1570032071.368 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-02T16:01:11.379Z,1570032071.379 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-02T16:01:11.782Z,1570032071.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-02T16:01:11.782Z,1570032071.782 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-02T16:01:15.073Z,1570032075.073 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.277462
2019-10-02T16:01:25.433Z,1570032085.433 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003288
2019-10-02T16:02:09.947Z,1570032129.947 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-02T16:02:09.948Z,1570032129.948 [Default:CheckIn:C.Wait] Stopped
2019-10-02T16:02:09.948Z,1570032129.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-02T16:02:09.948Z,1570032129.948 [Default:CheckIn:D] Running Loop=1
2019-10-02T16:02:10.258Z,1570032130.258 [CommandLine](IMPORTANT): got command quit
2019-10-02T16:02:10.351Z,1570032130.351 [Default:CheckIn:D] Stopped
2019-10-02T16:02:10.351Z,1570032130.351 [Default:CheckIn:E] Running Loop=1
2019-10-02T16:02:10.759Z,1570032130.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.230501 min 
2019-10-02T16:02:10.759Z,1570032130.759 [Default:CheckIn:E] Stopped
2019-10-02T16:02:10.760Z,1570032130.760 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-02T16:02:10.760Z,1570032130.760 [Default:CheckIn] Stopped
2019-10-02T16:02:10.760Z,1570032130.760 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-02T16:02:10.760Z,1570032130.760 [Default:CheckIn](INFO): Running loop #4
2019-10-02T16:02:10.760Z,1570032130.760 [Default:CheckIn] Running Loop=4
2019-10-02T16:02:10.760Z,1570032130.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-02T16:02:10.760Z,1570032130.760 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-02T16:02:11.265Z,1570032131.265 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-10-02T16:02:11.265Z,1570032131.265 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:11.265Z,1570032131.265 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:11.421Z,1570032131.421 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-02T16:02:11.421Z,1570032131.421 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:11.422Z,1570032131.422 [CommandLine](INFO): Join timeout helper Thread ID is 9074
2019-10-02T16:02:11.422Z,1570032131.422 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-02T16:02:11.422Z,1570032131.422 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:11.423Z,1570032131.423 [NavChartDb](INFO): Join timeout helper Thread ID is 9075
2019-10-02T16:02:11.649Z,1570032131.649 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:11.649Z,1570032131.649 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:11.661Z,1570032131.661 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2019-10-02T16:02:11.661Z,1570032131.661 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:11.661Z,1570032131.661 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 9076
2019-10-02T16:02:11.741Z,1570032131.741 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:11.741Z,1570032131.741 [WetLabsUBAT](INFO): Powering down
2019-10-02T16:02:11.757Z,1570032131.757 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:11.761Z,1570032131.761 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-02T16:02:11.761Z,1570032131.761 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:11.761Z,1570032131.761 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9077
2019-10-02T16:02:12.217Z,1570032132.217 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:12.218Z,1570032132.218 [WetLabsBB2FL](INFO): Powering down
2019-10-02T16:02:12.218Z,1570032132.218 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:12.237Z,1570032132.237 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-02T16:02:12.237Z,1570032132.237 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:12.237Z,1570032132.237 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9078
2019-10-02T16:02:12.357Z,1570032132.357 [NAL9602](DEBUG): Fix Requested
2019-10-02T16:02:12.751Z,1570032132.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160201.00,A,3648.16316,N,12147.28284,W,0.836,0.00,021019,,,A*71
2019-10-02T16:02:12.753Z,1570032132.753 [NAL9602](INFO): GPS fix at 20191002T160201: (36.802719, -121.788047)
2019-10-02T16:02:12.795Z,1570032132.795 [Default:CheckIn:Read_GPS] Stopped
2019-10-02T16:02:12.795Z,1570032132.795 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-02T16:02:12.909Z,1570032132.909 [CTD_Seabird](INFO): Powering down
2019-10-02T16:02:12.925Z,1570032132.925 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:12.925Z,1570032132.925 [CTD_Seabird](INFO): Powering down
2019-10-02T16:02:12.937Z,1570032132.937 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:12.958Z,1570032132.958 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2019-10-02T16:02:12.958Z,1570032132.958 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:12.958Z,1570032132.958 [BackSeatDriver](INFO): Join timeout helper Thread ID is 9079
2019-10-02T16:02:13.125Z,1570032133.125 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:13.125Z,1570032133.125 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.142Z,1570032133.142 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-02T16:02:13.142Z,1570032133.142 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.142Z,1570032133.142 [Radio_Surface](INFO): Join timeout helper Thread ID is 9080
2019-10-02T16:02:13.385Z,1570032133.385 [Radio_Surface](INFO): Powering down
2019-10-02T16:02:13.386Z,1570032133.386 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:13.386Z,1570032133.386 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.400Z,1570032133.400 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-02T16:02:13.400Z,1570032133.400 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.400Z,1570032133.400 [DataOverHttps](INFO): Join timeout helper Thread ID is 9081
2019-10-02T16:02:13.813Z,1570032133.813 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:13.813Z,1570032133.813 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.826Z,1570032133.826 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-02T16:02:13.826Z,1570032133.826 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.826Z,1570032133.826 [logger](INFO): Join timeout helper Thread ID is 9082
2019-10-02T16:02:13.857Z,1570032133.857 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:13.857Z,1570032133.857 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.865Z,1570032133.865 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-02T16:02:13.866Z,1570032133.866 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.866Z,1570032133.866 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-02T16:02:13.866Z,1570032133.866 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:13.866Z,1570032133.866 [controlThread](INFO): Join timeout helper Thread ID is 9083
2019-10-02T16:02:13.957Z,1570032133.957 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-02T16:02:13.957Z,1570032133.957 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-02T16:02:13.958Z,1570032133.958 [NAL9602](INFO): Powering down
2019-10-02T16:02:13.959Z,1570032133.959 [DAT](INFO): Powering down
2019-10-02T16:02:14.078Z,1570032134.078 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-02T16:02:14.079Z,1570032134.079 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-02T16:02:14.079Z,1570032134.079 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-02T16:02:14.080Z,1570032134.080 [MissionManager](INFO): Uninitializing Mission Default
2019-10-02T16:02:14.080Z,1570032134.080 [Default] Stopped
2019-10-02T16:02:14.080Z,1570032134.080 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-02T16:02:14.080Z,1570032134.080 [Default:B.GoToSurface] Stopped
2019-10-02T16:02:14.080Z,1570032134.080 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-02T16:02:14.080Z,1570032134.080 [Default:CheckIn] Stopped
2019-10-02T16:02:14.080Z,1570032134.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-02T16:02:14.080Z,1570032134.080 [Default:CheckIn:Read_Iridium] Stopped
2019-10-02T16:02:14.083Z,1570032134.083 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-02T16:02:14.083Z,1570032134.083 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-02T16:02:14.083Z,1570032134.083 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-02T16:02:14.083Z,1570032134.083 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-02T16:02:14.084Z,1570032134.084 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-02T16:02:14.084Z,1570032134.084 [BuoyancyServo](INFO): Powering down
2019-10-02T16:02:14.097Z,1570032134.097 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-02T16:02:14.097Z,1570032134.097 [ElevatorServo](INFO): Powering down
2019-10-02T16:02:14.098Z,1570032134.098 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-02T16:02:14.098Z,1570032134.098 [MassServo](INFO): Powering down
2019-10-02T16:02:14.099Z,1570032134.099 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-02T16:02:14.099Z,1570032134.099 [RudderServo](INFO): Powering down
2019-10-02T16:02:14.099Z,1570032134.099 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-02T16:02:14.100Z,1570032134.100 [ThrusterServo](INFO): Powering down
2019-10-02T16:02:14.100Z,1570032134.100 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-02T16:02:14.101Z,1570032134.101 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-02T16:02:14.101Z,1570032134.101 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-02T16:02:14.101Z,1570032134.101 [CBIT](DEBUG): Powering off loads.
2019-10-02T16:02:14.113Z,1570032134.113 [CBIT](DEBUG): Disabling WDT.
2019-10-02T16:02:14.125Z,1570032134.125 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-02T16:02:14.126Z,1570032134.126 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.220Z,1570032134.220 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.226Z,1570032134.226 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.276Z,1570032134.276 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.278Z,1570032134.278 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2019-10-02T16:02:14.278Z,1570032134.278 [WetLabsUBAT](INFO): WetLabs destructor: done
2019-10-02T16:02:14.280Z,1570032134.280 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.283Z,1570032134.283 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.286Z,1570032134.286 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.295Z,1570032134.295 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-02T16:02:14.364Z,1570032134.364 [logger ThreadHandler](INFO): Thread cancelled.