2020-05-29T20:54:50.835Z,1590785690.835 [Supervisor](DEBUG): Initializing supervisor. 2020-05-29T20:54:50.838Z,1590785690.838 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-05-29T20:54:50.839Z,1590785690.839 [SyncHandler](INFO): Protected caller Thread ID is 8819 2020-05-29T20:54:50.839Z,1590785690.839 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-05-29T20:54:50.840Z,1590785690.840 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-05-29T20:54:50.841Z,1590785690.841 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8820 2020-05-29T20:54:50.844Z,1590785690.844 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-05-29T20:54:50.861Z,1590785690.861 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-05-29T20:54:50.862Z,1590785690.862 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-05-29T20:54:50.862Z,1590785690.862 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8821 2020-05-29T20:54:50.863Z,1590785690.863 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-05-29T20:54:50.864Z,1590785690.864 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-05-29T20:54:50.865Z,1590785690.865 [logger ThreadHandler](INFO): Protected caller Thread ID is 8822 2020-05-29T20:54:50.867Z,1590785690.867 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-05-29T20:54:50.868Z,1590785690.868 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-05-29T20:54:50.869Z,1590785690.869 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-05-29T20:54:51.232Z,1590785691.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-05-29T20:54:51.233Z,1590785691.233 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-05-29T20:54:51.977Z,1590785691.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-05-29T20:54:51.977Z,1590785691.977 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-05-29T20:54:52.135Z,1590785692.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-05-29T20:54:52.136Z,1590785692.136 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-05-29T20:54:52.730Z,1590785692.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-05-29T20:54:52.731Z,1590785692.731 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-05-29T20:54:52.831Z,1590785692.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-05-29T20:54:52.832Z,1590785692.832 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-05-29T20:54:52.912Z,1590785692.912 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-05-29T20:54:53.277Z,1590785693.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-05-29T20:54:53.278Z,1590785693.278 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-05-29T20:54:53.441Z,1590785693.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-05-29T20:54:53.442Z,1590785693.442 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-05-29T20:54:53.552Z,1590785693.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-05-29T20:54:53.552Z,1590785693.552 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-05-29T20:54:53.786Z,1590785693.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-05-29T20:54:53.787Z,1590785693.787 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-05-29T20:54:54.400Z,1590785694.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-05-29T20:54:54.401Z,1590785694.401 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-05-29T20:54:54.946Z,1590785694.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-05-29T20:54:54.947Z,1590785694.947 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-05-29T20:54:55.056Z,1590785695.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-05-29T20:54:55.057Z,1590785695.057 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-05-29T20:54:55.267Z,1590785695.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-05-29T20:54:55.267Z,1590785695.267 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-05-29T20:54:55.520Z,1590785695.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-05-29T20:54:55.521Z,1590785695.521 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-05-29T20:54:56.317Z,1590785696.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-05-29T20:54:56.319Z,1590785696.319 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2020-05-29T20:54:56.320Z,1590785696.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2020-05-29T20:54:56.419Z,1590785696.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2020-05-29T20:54:56.570Z,1590785696.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2020-05-29T20:54:56.655Z,1590785696.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2020-05-29T20:54:56.738Z,1590785696.738 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2020-05-29T20:54:56.848Z,1590785696.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2020-05-29T20:54:57.050Z,1590785697.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2020-05-29T20:54:57.354Z,1590785697.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-05-29T20:54:57.354Z,1590785697.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2020-05-29T20:54:57.470Z,1590785697.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2020-05-29T20:54:57.565Z,1590785697.565 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2020-05-29T20:54:57.666Z,1590785697.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2020-05-29T20:54:57.761Z,1590785697.761 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2020-05-29T20:54:57.762Z,1590785697.762 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-05-29T20:54:57.770Z,1590785697.770 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-05-29T20:54:57.852Z,1590785697.852 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-05-29T20:54:57.853Z,1590785697.853 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-05-29T20:54:57.872Z,1590785697.872 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-05-29T20:54:57.873Z,1590785697.873 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-05-29T20:54:57.917Z,1590785697.917 [DepthRateCalculator] Loaded 2020-05-29T20:54:57.917Z,1590785697.917 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-05-29T20:54:57.924Z,1590785697.924 [PitchRateCalculator] Loaded 2020-05-29T20:54:57.925Z,1590785697.925 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-05-29T20:54:57.943Z,1590785697.943 [SpeedCalculator] Loaded 2020-05-29T20:54:57.944Z,1590785697.944 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-05-29T20:54:57.971Z,1590785697.971 [TempGradientCalculator] Loaded 2020-05-29T20:54:57.971Z,1590785697.971 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-05-29T20:54:57.978Z,1590785697.978 [YawRateCalculator] Loaded 2020-05-29T20:54:57.978Z,1590785697.978 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-05-29T20:54:58.029Z,1590785698.029 [ElevatorOffsetCalculator] Loaded 2020-05-29T20:54:58.029Z,1590785698.029 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-05-29T20:54:58.029Z,1590785698.029 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-05-29T20:54:58.030Z,1590785698.030 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-05-29T20:54:58.081Z,1590785698.081 [VerticalControl](DEBUG): Construct VerticalControl. 2020-05-29T20:54:58.203Z,1590785698.203 [VerticalControl] Loaded 2020-05-29T20:54:58.204Z,1590785698.204 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-05-29T20:54:58.204Z,1590785698.204 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-05-29T20:54:58.306Z,1590785698.306 [HorizontalControl] Loaded 2020-05-29T20:54:58.306Z,1590785698.306 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-05-29T20:54:58.307Z,1590785698.307 [SpeedControl](DEBUG): Construct SpeedControl. 2020-05-29T20:54:58.313Z,1590785698.313 [SpeedControl] Loaded 2020-05-29T20:54:58.314Z,1590785698.314 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-05-29T20:54:58.314Z,1590785698.314 [LoopControl](DEBUG): Construct LoopControl. 2020-05-29T20:54:58.315Z,1590785698.315 [LoopControl] Loaded 2020-05-29T20:54:58.315Z,1590785698.315 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-05-29T20:54:58.316Z,1590785698.316 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-05-29T20:54:58.316Z,1590785698.316 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-05-29T20:54:58.456Z,1590785698.456 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-05-29T20:54:58.457Z,1590785698.457 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-05-29T20:54:58.483Z,1590785698.483 [NavChart] Loaded 2020-05-29T20:54:58.483Z,1590785698.483 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-05-29T20:54:58.488Z,1590785698.488 [UniversalFixResidualReporter] Loaded 2020-05-29T20:54:58.488Z,1590785698.488 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-05-29T20:54:58.489Z,1590785698.489 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-05-29T20:54:58.490Z,1590785698.490 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-05-29T20:54:58.595Z,1590785698.595 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-05-29T20:54:58.596Z,1590785698.596 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-05-29T20:54:59.006Z,1590785699.006 [DataOverHttps] Loaded 2020-05-29T20:54:59.006Z,1590785699.006 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-05-29T20:54:59.007Z,1590785699.007 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FC4E0 2020-05-29T20:54:59.008Z,1590785699.008 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8904 2020-05-29T20:54:59.026Z,1590785699.026 [Depth_Keller] Loaded 2020-05-29T20:54:59.026Z,1590785699.026 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-05-29T20:54:59.032Z,1590785699.032 [DropWeight] Loaded 2020-05-29T20:54:59.032Z,1590785699.032 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-05-29T20:54:59.155Z,1590785699.155 [NAL9602] Loaded 2020-05-29T20:54:59.156Z,1590785699.156 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-05-29T20:54:59.205Z,1590785699.205 [Onboard] Loaded 2020-05-29T20:54:59.205Z,1590785699.205 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-05-29T20:54:59.209Z,1590785699.209 [Radio_Surface] Loaded 2020-05-29T20:54:59.210Z,1590785699.210 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-05-29T20:54:59.211Z,1590785699.211 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4092C4E0 2020-05-29T20:54:59.211Z,1590785699.211 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8905 2020-05-29T20:54:59.388Z,1590785699.388 [DAT] Loaded 2020-05-29T20:54:59.388Z,1590785699.388 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-05-29T20:55:01.476Z,1590785701.476 [BPC1] Loaded 2020-05-29T20:55:01.477Z,1590785701.477 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-05-29T20:55:01.477Z,1590785701.477 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-05-29T20:55:01.478Z,1590785701.478 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-05-29T20:55:01.740Z,1590785701.740 [BuoyancyServo] Loaded 2020-05-29T20:55:01.753Z,1590785701.753 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-05-29T20:55:01.772Z,1590785701.772 [ElevatorServo] Loaded 2020-05-29T20:55:01.772Z,1590785701.772 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-05-29T20:55:01.786Z,1590785701.786 [MassServo] Loaded 2020-05-29T20:55:01.787Z,1590785701.787 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-05-29T20:55:01.801Z,1590785701.801 [RudderServo] Loaded 2020-05-29T20:55:01.801Z,1590785701.801 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-05-29T20:55:01.815Z,1590785701.815 [ThrusterServo] Loaded 2020-05-29T20:55:01.815Z,1590785701.815 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-05-29T20:55:01.815Z,1590785701.815 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-05-29T20:55:01.816Z,1590785701.816 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-05-29T20:55:01.903Z,1590785701.903 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-05-29T20:55:01.904Z,1590785701.904 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-05-29T20:55:01.932Z,1590785701.932 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-05-29T20:55:01.932Z,1590785701.932 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-05-29T20:55:02.235Z,1590785702.235 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-05-29T20:55:02.235Z,1590785702.235 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-05-29T20:55:02.464Z,1590785702.464 [ESPComponent] Loaded 2020-05-29T20:55:02.465Z,1590785702.465 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-05-29T20:55:02.484Z,1590785702.484 [PAR_Licor] Loaded 2020-05-29T20:55:02.484Z,1590785702.484 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-05-29T20:55:02.493Z,1590785702.493 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-05-29T20:55:02.494Z,1590785702.494 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-05-29T20:55:02.500Z,1590785702.500 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-05-29T20:55:02.500Z,1590785702.500 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-05-29T20:55:02.505Z,1590785702.505 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-05-29T20:55:02.505Z,1590785702.505 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-05-29T20:55:02.510Z,1590785702.510 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-05-29T20:55:02.510Z,1590785702.510 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-05-29T20:55:02.515Z,1590785702.515 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-05-29T20:55:02.516Z,1590785702.516 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-05-29T20:55:02.521Z,1590785702.521 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-05-29T20:55:02.521Z,1590785702.521 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-05-29T20:55:02.526Z,1590785702.526 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-05-29T20:55:02.526Z,1590785702.526 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-05-29T20:55:02.531Z,1590785702.531 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.537Z,1590785702.537 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.537Z,1590785702.537 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.538Z,1590785702.538 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.543Z,1590785702.543 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.543Z,1590785702.543 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.548Z,1590785702.548 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.548Z,1590785702.548 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-29T20:55:02.553Z,1590785702.553 [WetLabsBB2FL] Loaded 2020-05-29T20:55:02.554Z,1590785702.554 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-05-29T20:55:02.555Z,1590785702.555 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD14E0 2020-05-29T20:55:02.555Z,1590785702.555 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8906 2020-05-29T20:55:02.556Z,1590785702.556 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-05-29T20:55:02.557Z,1590785702.557 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-05-29T20:55:02.701Z,1590785702.701 [SBIT](DEBUG): Construct Startup Built In Test. 2020-05-29T20:55:02.715Z,1590785702.715 [SBIT] Loaded 2020-05-29T20:55:02.716Z,1590785702.716 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-05-29T20:55:02.717Z,1590785702.717 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-05-29T20:55:02.734Z,1590785702.734 [IBIT] Loaded 2020-05-29T20:55:02.734Z,1590785702.734 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-05-29T20:55:02.739Z,1590785702.739 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-05-29T20:55:02.924Z,1590785702.924 [CBIT] Loaded 2020-05-29T20:55:02.925Z,1590785702.925 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-05-29T20:55:02.925Z,1590785702.925 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-05-29T20:55:02.930Z,1590785702.930 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-05-29T20:55:02.931Z,1590785702.931 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-05-29T20:55:02.942Z,1590785702.942 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-05-29T20:55:02.943Z,1590785702.943 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C4D4E0 2020-05-29T20:55:02.943Z,1590785702.943 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8907 2020-05-29T20:55:02.949Z,1590785702.949 [Supervisor](INFO): Main Thread ID is 8818 2020-05-29T20:55:02.949Z,1590785702.949 [Supervisor](DEBUG): Running supervisor. 2020-05-29T20:55:02.950Z,1590785702.950 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8908 2020-05-29T20:55:02.953Z,1590785702.953 [controlThread ThreadHandler](INFO): Handler Thread ID is 8909 2020-05-29T20:55:02.953Z,1590785702.953 [controlThread](DEBUG): Initializing ControlThread 2020-05-29T20:55:02.954Z,1590785702.954 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-05-29T20:55:02.955Z,1590785702.955 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-05-29T20:55:02.955Z,1590785702.955 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-05-29T20:55:02.956Z,1590785702.956 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-05-29T20:55:02.956Z,1590785702.956 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-05-29T20:55:02.957Z,1590785702.957 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-05-29T20:55:02.957Z,1590785702.957 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-05-29T20:55:02.960Z,1590785702.960 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-05-29T20:55:02.960Z,1590785702.960 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-05-29T20:55:02.961Z,1590785702.961 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-05-29T20:55:02.962Z,1590785702.962 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-05-29T20:55:02.963Z,1590785702.963 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-05-29T20:55:02.973Z,1590785702.973 [SBIT](INFO): Initialize SBIT Component. 2020-05-29T20:55:02.974Z,1590785702.974 [SBIT](IMPORTANT): git: 2020-05-06_C-4-ge907442 2020-05-29T20:55:02.974Z,1590785702.974 [SBIT](INFO): git hash: e907442e8d324ef9e313fad2e4afa1f9562153e8 2020-05-29T20:55:02.974Z,1590785702.974 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-05-29T20:55:02.974Z,1590785702.974 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 2020-05-29T20:55:02.976Z,1590785702.976 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2020-05-29T20:55:02.976Z,1590785702.976 [IBIT](INFO): Initialize IBIT Component. 2020-05-29T20:55:02.978Z,1590785702.978 [CBIT](DEBUG): Initialize CBIT Component. 2020-05-29T20:55:02.979Z,1590785702.979 [logger ThreadHandler](INFO): Handler Thread ID is 8910 2020-05-29T20:55:02.989Z,1590785702.989 [CBIT](DEBUG): Initialized mux pins. 2020-05-29T20:55:02.989Z,1590785702.989 [CBIT](DEBUG): Initializing the watchdog timer. 2020-05-29T20:55:02.997Z,1590785702.997 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8911 2020-05-29T20:55:02.998Z,1590785702.998 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-05-29T20:55:03.009Z,1590785703.009 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8912 2020-05-29T20:55:03.013Z,1590785703.013 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-05-29T20:55:03.013Z,1590785703.013 [CBIT](DEBUG): Initializing heartbeat. 2020-05-29T20:55:03.021Z,1590785703.021 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8913 2020-05-29T20:55:03.023Z,1590785703.023 [WetLabsBB2FL](INFO): Powering down 2020-05-29T20:55:03.045Z,1590785703.045 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8914 2020-05-29T20:55:03.050Z,1590785703.050 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-05-29T20:55:03.050Z,1590785703.050 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-05-29T20:55:03.050Z,1590785703.050 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-05-29T20:55:03.050Z,1590785703.050 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-05-29T20:55:03.051Z,1590785703.051 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-05-29T20:55:03.051Z,1590785703.051 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-05-29T20:55:03.051Z,1590785703.051 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-05-29T20:55:03.051Z,1590785703.051 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-05-29T20:55:03.051Z,1590785703.051 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-05-29T20:55:03.052Z,1590785703.052 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-05-29T20:55:03.052Z,1590785703.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-05-29T20:55:03.052Z,1590785703.052 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-05-29T20:55:03.052Z,1590785703.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-05-29T20:55:03.052Z,1590785703.052 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-05-29T20:55:03.053Z,1590785703.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-05-29T20:55:03.053Z,1590785703.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-05-29T20:55:03.085Z,1590785703.085 [CBIT](DEBUG): Deactivating GF circuits. 2020-05-29T20:55:03.086Z,1590785703.086 [CBIT](DEBUG): Deactivating emergency mode. 2020-05-29T20:55:03.121Z,1590785703.121 [CBIT](DEBUG): Backplane powered. 2020-05-29T20:55:03.122Z,1590785703.122 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-05-29T20:55:03.133Z,1590785703.133 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-29T20:55:03.163Z,1590785703.163 [MissionManager](DEBUG): 2020-05-29T20:55:03.164Z,1590785703.164 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-05-29T20:55:03.251Z,1590785703.251 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-05-29T20:55:03.252Z,1590785703.252 [Default:A.Wait](DEBUG): Construct Wait. 2020-05-29T20:55:03.254Z,1590785703.254 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-29T20:55:03.301Z,1590785703.301 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-05-29T20:55:03.304Z,1590785703.304 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-05-29T20:55:03.327Z,1590785703.327 [Default:E.Execute](DEBUG): Construct Execute. 2020-05-29T20:55:03.347Z,1590785703.347 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-05-29T20:55:03.352Z,1590785703.352 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,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-05-29T20:55:03.360Z,1590785703.360 [Depth_Keller](ERROR): Pressure reading out of range: 1829.799438 decibar 2020-05-29T20:55:03.394Z,1590785703.394 [DAT](INFO): Start 2020-05-29T20:55:03.396Z,1590785703.396 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-05-29T20:55:03.396Z,1590785703.396 [DAT](INFO): Powering up 2020-05-29T20:55:03.397Z,1590785703.397 [DAT](DEBUG): Initializing DAT. 2020-05-29T20:55:03.413Z,1590785703.413 [Radio_Surface](INFO): Powering up 2020-05-29T20:55:03.449Z,1590785703.449 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-05-29T20:55:03.552Z,1590785703.552 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-05-29T20:55:03.593Z,1590785703.593 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-05-29T20:55:03.609Z,1590785703.609 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-05-29T20:55:03.611Z,1590785703.611 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-05-29T20:55:03.617Z,1590785703.617 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-05-29T20:55:03.618Z,1590785703.618 [MassServo](DEBUG): Initializing EZServoServo. 2020-05-29T20:55:03.629Z,1590785703.629 [MassServo](DEBUG): Initializing MassServo. 2020-05-29T20:55:03.630Z,1590785703.630 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-29T20:55:03.641Z,1590785703.641 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-29T20:55:03.642Z,1590785703.642 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-05-29T20:55:03.649Z,1590785703.649 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-05-29T20:55:03.895Z,1590785703.895 [DAT](INFO): Starting 2020-05-29T20:55:04.181Z,1590785704.181 [DAT](INFO): Starting 2020-05-29T20:55:04.529Z,1590785704.529 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-05-29T20:55:04.529Z,1590785704.529 [RudderServo](FAULT): Rudder failed to initialize 2020-05-29T20:55:04.529Z,1590785704.529 [RudderServo] Communications Fault, FailCount= 1 2020-05-29T20:55:04.529Z,1590785704.529 [RudderServo](ERROR): Communications Fault 2020-05-29T20:55:04.640Z,1590785704.640 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-05-29T20:55:04.670Z,1590785704.670 [DAT](INFO): Starting 2020-05-29T20:55:04.862Z,1590785704.862 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-05-29T20:55:04.862Z,1590785704.862 [RudderServo](INFO): Powering down 2020-05-29T20:55:05.075Z,1590785705.075 [DAT](INFO): Starting 2020-05-29T20:55:05.473Z,1590785705.473 [DAT](INFO): Starting 2020-05-29T20:55:05.499Z,1590785705.499 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-29T20:55:05.618Z,1590785705.618 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-29T20:55:05.622Z,1590785705.622 [CBIT](INFO): Clearing failed state for component RudderServo 2020-05-29T20:55:05.622Z,1590785705.622 [RudderServo] No Fault, FailCount= 1 2020-05-29T20:55:05.877Z,1590785705.877 [DAT](INFO): Starting 2020-05-29T20:55:06.281Z,1590785706.281 [DAT](INFO): Starting 2020-05-29T20:55:06.688Z,1590785706.688 [DAT](INFO): Starting 2020-05-29T20:55:07.089Z,1590785707.089 [DAT](INFO): Starting 2020-05-29T20:55:07.497Z,1590785707.497 [DAT](INFO): Starting 2020-05-29T20:55:07.897Z,1590785707.897 [DAT](INFO): Starting 2020-05-29T20:55:08.301Z,1590785708.301 [DAT](INFO): Starting 2020-05-29T20:55:08.705Z,1590785708.705 [DAT](INFO): Starting 2020-05-29T20:55:09.113Z,1590785709.113 [DAT](INFO): Starting 2020-05-29T20:55:09.513Z,1590785709.513 [DAT](INFO): Starting 2020-05-29T20:55:10.013Z,1590785710.013 [DAT](INFO): Starting 2020-05-29T20:55:10.407Z,1590785710.407 [DAT](INFO): Starting 2020-05-29T20:55:10.801Z,1590785710.801 [DAT](INFO): Starting 2020-05-29T20:55:11.205Z,1590785711.205 [DAT](INFO): Starting 2020-05-29T20:55:11.625Z,1590785711.625 [DAT](INFO): Starting 2020-05-29T20:55:12.013Z,1590785712.013 [DAT](INFO): Starting 2020-05-29T20:55:12.416Z,1590785712.416 [DAT](INFO): Starting 2020-05-29T20:55:12.831Z,1590785712.831 [DAT](INFO): Starting 2020-05-29T20:55:13.237Z,1590785713.237 [DAT](INFO): Starting 2020-05-29T20:55:13.629Z,1590785713.629 [DAT](INFO): Starting 2020-05-29T20:55:14.037Z,1590785714.037 [DAT](INFO): Starting 2020-05-29T20:55:14.437Z,1590785714.437 [DAT](INFO): Starting 2020-05-29T20:55:14.841Z,1590785714.841 [DAT](INFO): Starting 2020-05-29T20:55:14.843Z,1590785714.843 [DAT](INFO): unknown deviceResponse_: Teledyne Benthos DAT-900 Series 2020-05-29T20:55:15.245Z,1590785715.245 [DAT](INFO): Starting 2020-05-29T20:55:15.650Z,1590785715.650 [DAT](INFO): Starting 2020-05-29T20:55:16.055Z,1590785716.055 [DAT](INFO): Starting 2020-05-29T20:55:16.056Z,1590785716.056 [DAT](INFO): unknown deviceResponse_: MF Frequency Band 2020-05-29T20:55:16.456Z,1590785716.456 [DAT](INFO): Starting 2020-05-29T20:55:16.458Z,1590785716.458 [DAT](INFO): unknown deviceResponse_: Directional Acoustic Transponder version 8.14.0 2020-05-29T20:55:16.459Z,1590785716.459 [DAT](INFO): unknown deviceResponse_: May 29 2020 20:53:20 2020-05-29T20:55:16.861Z,1590785716.861 [DAT](INFO): Starting 2020-05-29T20:55:17.003Z,1590785717.003 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004685 2020-05-29T20:55:17.265Z,1590785717.265 [DAT](INFO): Starting 2020-05-29T20:55:17.267Z,1590785717.267 [DAT](INFO): unknown deviceResponse_: Features enabled [Bearing] 2020-05-29T20:55:17.268Z,1590785717.268 [DAT](INFO): commRate: 800 2020-05-29T20:55:17.268Z,1590785717.268 [DAT](INFO): commRate: 800 2020-05-29T20:55:17.673Z,1590785717.673 [DAT](INFO): Starting 2020-05-29T20:55:17.673Z,1590785717.673 [DAT](INFO): commRate: 800 2020-05-29T20:55:18.073Z,1590785718.073 [DAT](INFO): Starting 2020-05-29T20:55:18.073Z,1590785718.073 [DAT](INFO): commRate: 800 2020-05-29T20:55:18.477Z,1590785718.477 [DAT](INFO): Starting 2020-05-29T20:55:18.478Z,1590785718.478 [DAT](INFO): commRate: 800 2020-05-29T20:55:18.880Z,1590785718.880 [DAT](INFO): Starting 2020-05-29T20:55:18.881Z,1590785718.881 [DAT](INFO): commRate: 800 2020-05-29T20:55:19.285Z,1590785719.285 [DAT](INFO): Starting 2020-05-29T20:55:19.286Z,1590785719.286 [DAT](INFO): commRate: 800 2020-05-29T20:55:19.688Z,1590785719.688 [DAT](INFO): Starting 2020-05-29T20:55:19.689Z,1590785719.689 [DAT](INFO): commRate: 800 2020-05-29T20:55:20.106Z,1590785720.106 [DAT](INFO): Starting 2020-05-29T20:55:20.106Z,1590785720.106 [DAT](INFO): commRate: 800 2020-05-29T20:55:20.497Z,1590785720.497 [DAT](INFO): Starting 2020-05-29T20:55:20.497Z,1590785720.497 [DAT](INFO): commRate: 800 2020-05-29T20:55:20.901Z,1590785720.901 [DAT](INFO): Starting 2020-05-29T20:55:20.902Z,1590785720.902 [DAT](INFO): commRate: 800 2020-05-29T20:55:21.304Z,1590785721.304 [DAT](INFO): Starting 2020-05-29T20:55:21.304Z,1590785721.304 [DAT](INFO): commRate: 800 2020-05-29T20:55:21.709Z,1590785721.709 [DAT](INFO): Starting 2020-05-29T20:55:21.709Z,1590785721.709 [DAT](INFO): commRate: 800 2020-05-29T20:55:22.112Z,1590785722.112 [DAT](INFO): Starting 2020-05-29T20:55:22.113Z,1590785722.113 [DAT](INFO): commRate: 800 2020-05-29T20:55:22.517Z,1590785722.517 [DAT](INFO): Starting 2020-05-29T20:55:22.518Z,1590785722.518 [DAT](INFO): commRate: 800 2020-05-29T20:55:22.921Z,1590785722.921 [DAT](INFO): Starting 2020-05-29T20:55:22.921Z,1590785722.921 [DAT](INFO): commRate: 800 2020-05-29T20:55:23.336Z,1590785723.336 [DAT](INFO): Starting 2020-05-29T20:55:23.337Z,1590785723.337 [DAT](INFO): commRate: 800 2020-05-29T20:55:23.404Z,1590785723.404 [SBIT](IMPORTANT): Beginning Startup BIT 2020-05-29T20:55:23.424Z,1590785723.424 [CBIT](IMPORTANT): Beginning ground fault scan 2020-05-29T20:55:23.729Z,1590785723.729 [DAT](INFO): Starting 2020-05-29T20:55:23.730Z,1590785723.730 [DAT](INFO): commRate: 800 2020-05-29T20:55:24.133Z,1590785724.133 [DAT](INFO): Starting 2020-05-29T20:55:24.133Z,1590785724.133 [DAT](INFO): commRate: 800 2020-05-29T20:55:24.537Z,1590785724.537 [DAT](INFO): Starting 2020-05-29T20:55:24.537Z,1590785724.537 [DAT](INFO): commRate: 800 2020-05-29T20:55:24.942Z,1590785724.942 [DAT](INFO): Starting 2020-05-29T20:55:24.942Z,1590785724.942 [DAT](INFO): commRate: 800 2020-05-29T20:55:25.345Z,1590785725.345 [DAT](INFO): Starting 2020-05-29T20:55:25.346Z,1590785725.346 [DAT](INFO): commRate: 800 2020-05-29T20:55:25.749Z,1590785725.749 [DAT](INFO): Starting 2020-05-29T20:55:25.750Z,1590785725.750 [DAT](INFO): commRate: 800 2020-05-29T20:55:26.454Z,1590785726.454 [DAT](INFO): Starting 2020-05-29T20:55:26.454Z,1590785726.454 [DAT](INFO): commRate: 800 2020-05-29T20:55:26.833Z,1590785726.833 [DAT](INFO): Starting 2020-05-29T20:55:26.834Z,1590785726.834 [DAT](INFO): commRate: 800 2020-05-29T20:55:27.240Z,1590785727.240 [DAT](INFO): Starting 2020-05-29T20:55:27.241Z,1590785727.241 [DAT](INFO): commRate: 800 2020-05-29T20:55:27.642Z,1590785727.642 [DAT](INFO): Starting 2020-05-29T20:55:27.642Z,1590785727.642 [DAT](INFO): commRate: 800 2020-05-29T20:55:28.046Z,1590785728.046 [DAT](INFO): Starting 2020-05-29T20:55:28.047Z,1590785728.047 [DAT](INFO): commRate: 800 2020-05-29T20:55:28.449Z,1590785728.449 [DAT](INFO): Starting 2020-05-29T20:55:28.449Z,1590785728.449 [DAT](INFO): commRate: 800 2020-05-29T20:55:28.857Z,1590785728.857 [DAT](INFO): Starting 2020-05-29T20:55:28.857Z,1590785728.857 [DAT](INFO): commRate: 800 2020-05-29T20:55:29.243Z,1590785729.243 [NAL9602](INFO): Powering up NAL9602 2020-05-29T20:55:29.259Z,1590785729.259 [DAT](INFO): Starting 2020-05-29T20:55:29.260Z,1590785729.260 [DAT](INFO): commRate: 800 2020-05-29T20:55:29.662Z,1590785729.662 [DAT](INFO): Starting 2020-05-29T20:55:29.662Z,1590785729.662 [DAT](INFO): commRate: 800 2020-05-29T20:55:30.074Z,1590785730.074 [DAT](INFO): Starting 2020-05-29T20:55:30.074Z,1590785730.074 [DAT](INFO): commRate: 800 2020-05-29T20:55:30.470Z,1590785730.470 [DAT](INFO): Starting 2020-05-29T20:55:30.471Z,1590785730.471 [DAT](INFO): commRate: 800 2020-05-29T20:55:30.874Z,1590785730.874 [DAT](INFO): Starting 2020-05-29T20:55:30.874Z,1590785730.874 [DAT](INFO): commRate: 800 2020-05-29T20:55:31.277Z,1590785731.277 [DAT](INFO): Starting 2020-05-29T20:55:31.277Z,1590785731.277 [DAT](INFO): commRate: 800 2020-05-29T20:55:31.685Z,1590785731.685 [DAT](INFO): Starting 2020-05-29T20:55:31.686Z,1590785731.686 [DAT](INFO): commRate: 800 2020-05-29T20:55:32.086Z,1590785732.086 [DAT](INFO): Starting 2020-05-29T20:55:32.086Z,1590785732.086 [DAT](INFO): commRate: 800 2020-05-29T20:55:32.489Z,1590785732.489 [DAT](INFO): Starting 2020-05-29T20:55:32.489Z,1590785732.489 [DAT](INFO): commRate: 800 2020-05-29T20:55:32.893Z,1590785732.893 [DAT](INFO): Starting 2020-05-29T20:55:32.893Z,1590785732.893 [DAT](INFO): commRate: 800 2020-05-29T20:55:33.297Z,1590785733.297 [DAT](INFO): Starting 2020-05-29T20:55:33.297Z,1590785733.297 [DAT](INFO): commRate: 800 2020-05-29T20:55:33.715Z,1590785733.715 [DAT](INFO): Starting 2020-05-29T20:55:33.715Z,1590785733.715 [DAT](INFO): commRate: 800 2020-05-29T20:55:34.105Z,1590785734.105 [DAT](INFO): Starting 2020-05-29T20:55:34.105Z,1590785734.105 [DAT](INFO): commRate: 800 2020-05-29T20:55:34.510Z,1590785734.510 [DAT](INFO): Starting 2020-05-29T20:55:34.511Z,1590785734.511 [DAT](INFO): commRate: 800 2020-05-29T20:55:34.602Z,1590785734.602 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.029279 CHAN A1 (24V): 0.008324 CHAN A2 (12V): -0.001608 CHAN A3 (5V): -0.002519 CHAN B0 (3.3V): -0.001160 CHAN B1 (3.15aV): -0.001153 CHAN B2 (3.15bV): -0.001506 CHAN B3 (GND): -0.001560 OPEN: 0.003746 Full Scale Calc: 4.765 mA, -1.589 mA 2020-05-29T20:55:34.913Z,1590785734.913 [DAT](INFO): Starting 2020-05-29T20:55:34.913Z,1590785734.913 [DAT](INFO): commRate: 800 2020-05-29T20:55:35.316Z,1590785735.316 [DAT](INFO): Starting 2020-05-29T20:55:35.317Z,1590785735.317 [DAT](INFO): commRate: 800 2020-05-29T20:55:35.720Z,1590785735.720 [DAT](INFO): Starting 2020-05-29T20:55:35.720Z,1590785735.720 [DAT](INFO): commRate: 800 2020-05-29T20:55:36.128Z,1590785736.128 [DAT](INFO): Starting 2020-05-29T20:55:36.128Z,1590785736.128 [DAT](INFO): commRate: 800 2020-05-29T20:55:36.529Z,1590785736.529 [DAT](INFO): Starting 2020-05-29T20:55:36.529Z,1590785736.529 [DAT](INFO): commRate: 800 2020-05-29T20:55:36.932Z,1590785736.932 [DAT](INFO): Starting 2020-05-29T20:55:36.933Z,1590785736.933 [DAT](INFO): commRate: 800 2020-05-29T20:55:37.337Z,1590785737.337 [DAT](INFO): Starting 2020-05-29T20:55:37.337Z,1590785737.337 [DAT](INFO): commRate: 800 2020-05-29T20:55:37.744Z,1590785737.744 [DAT](INFO): Starting 2020-05-29T20:55:37.745Z,1590785737.745 [DAT](INFO): commRate: 800 2020-05-29T20:55:38.144Z,1590785738.144 [DAT](INFO): Starting 2020-05-29T20:55:38.144Z,1590785738.144 [DAT](INFO): commRate: 800 2020-05-29T20:55:38.549Z,1590785738.549 [DAT](INFO): Starting 2020-05-29T20:55:38.549Z,1590785738.549 [DAT](INFO): commRate: 800 2020-05-29T20:55:38.953Z,1590785738.953 [DAT](INFO): Starting 2020-05-29T20:55:38.953Z,1590785738.953 [DAT](INFO): commRate: 800 2020-05-29T20:55:39.477Z,1590785739.477 [DAT](INFO): Starting 2020-05-29T20:55:39.478Z,1590785739.478 [DAT](INFO): commRate: 800 2020-05-29T20:55:39.896Z,1590785739.896 [DAT](INFO): Starting 2020-05-29T20:55:39.896Z,1590785739.896 [DAT](INFO): commRate: 800 2020-05-29T20:55:40.275Z,1590785740.275 [NAL9602](INFO): NAL9602 initialized 2020-05-29T20:55:40.300Z,1590785740.300 [DAT](INFO): Starting 2020-05-29T20:55:40.301Z,1590785740.301 [DAT](INFO): commRate: 800 2020-05-29T20:55:40.690Z,1590785740.690 [DAT](INFO): Starting 2020-05-29T20:55:40.691Z,1590785740.691 [DAT](INFO): commRate: 800 2020-05-29T20:55:41.135Z,1590785741.135 [DAT](INFO): Starting 2020-05-29T20:55:41.135Z,1590785741.135 [DAT](INFO): commRate: 800 2020-05-29T20:55:41.516Z,1590785741.516 [DAT](INFO): Starting 2020-05-29T20:55:41.517Z,1590785741.517 [DAT](INFO): commRate: 800 2020-05-29T20:55:41.918Z,1590785741.918 [DAT](INFO): Starting 2020-05-29T20:55:41.919Z,1590785741.919 [DAT](INFO): commRate: 800 2020-05-29T20:55:42.337Z,1590785742.337 [DAT](INFO): Starting 2020-05-29T20:55:42.338Z,1590785742.338 [DAT](INFO): commRate: 800 2020-05-29T20:55:42.724Z,1590785742.724 [DAT](INFO): Starting 2020-05-29T20:55:42.724Z,1590785742.724 [DAT](INFO): commRate: 800 2020-05-29T20:55:43.140Z,1590785743.140 [DAT](INFO): Starting 2020-05-29T20:55:43.140Z,1590785743.140 [DAT](INFO): commRate: 800 2020-05-29T20:55:43.535Z,1590785743.535 [DAT](INFO): Starting 2020-05-29T20:55:43.536Z,1590785743.536 [DAT](INFO): commRate: 800 2020-05-29T20:55:43.940Z,1590785743.940 [DAT](INFO): Starting 2020-05-29T20:55:43.941Z,1590785743.941 [DAT](INFO): commRate: 800 2020-05-29T20:55:44.336Z,1590785744.336 [DAT](INFO): Starting 2020-05-29T20:55:44.337Z,1590785744.337 [DAT](INFO): commRate: 800 2020-05-29T20:55:44.731Z,1590785744.731 [DAT](INFO): Starting 2020-05-29T20:55:44.732Z,1590785744.732 [DAT](INFO): commRate: 800 2020-05-29T20:55:45.137Z,1590785745.137 [DAT](INFO): Starting 2020-05-29T20:55:45.138Z,1590785745.138 [DAT](INFO): commRate: 800 2020-05-29T20:55:45.538Z,1590785745.538 [DAT](INFO): Starting 2020-05-29T20:55:45.539Z,1590785745.539 [DAT](INFO): commRate: 800 2020-05-29T20:55:45.941Z,1590785745.941 [DAT](INFO): Starting 2020-05-29T20:55:45.942Z,1590785745.942 [DAT](INFO): commRate: 800 2020-05-29T20:55:46.346Z,1590785746.346 [DAT](INFO): Starting 2020-05-29T20:55:46.347Z,1590785746.347 [DAT](INFO): commRate: 800 2020-05-29T20:55:46.753Z,1590785746.753 [DAT](INFO): Starting 2020-05-29T20:55:46.753Z,1590785746.753 [DAT](INFO): commRate: 800 2020-05-29T20:55:47.154Z,1590785747.154 [DAT](INFO): Starting 2020-05-29T20:55:47.155Z,1590785747.155 [DAT](INFO): commRate: 800 2020-05-29T20:55:47.567Z,1590785747.567 [DAT](INFO): Starting 2020-05-29T20:55:47.568Z,1590785747.568 [DAT](INFO): commRate: 800 2020-05-29T20:55:47.976Z,1590785747.976 [DAT](INFO): Starting 2020-05-29T20:55:47.976Z,1590785747.976 [DAT](INFO): commRate: 800 2020-05-29T20:55:48.379Z,1590785748.379 [DAT](INFO): Starting 2020-05-29T20:55:48.379Z,1590785748.379 [DAT](INFO): commRate: 800 2020-05-29T20:55:48.787Z,1590785748.787 [DAT](INFO): Starting 2020-05-29T20:55:48.788Z,1590785748.788 [DAT](INFO): commRate: 800 2020-05-29T20:55:49.173Z,1590785749.173 [DAT](INFO): Starting 2020-05-29T20:55:49.174Z,1590785749.174 [DAT](INFO): commRate: 800 2020-05-29T20:55:49.577Z,1590785749.577 [DAT](INFO): Starting 2020-05-29T20:55:49.578Z,1590785749.578 [DAT](INFO): commRate: 800 2020-05-29T20:55:49.983Z,1590785749.983 [DAT](INFO): Starting 2020-05-29T20:55:49.984Z,1590785749.984 [DAT](INFO): commRate: 800 2020-05-29T20:55:50.390Z,1590785750.390 [DAT](INFO): Starting 2020-05-29T20:55:50.391Z,1590785750.391 [DAT](INFO): commRate: 800 2020-05-29T20:55:50.789Z,1590785750.789 [DAT](INFO): Starting 2020-05-29T20:55:50.790Z,1590785750.790 [DAT](INFO): commRate: 800 2020-05-29T20:55:51.193Z,1590785751.193 [DAT](INFO): Starting 2020-05-29T20:55:51.194Z,1590785751.194 [DAT](INFO): commRate: 800 2020-05-29T20:55:51.605Z,1590785751.605 [DAT](INFO): Starting 2020-05-29T20:55:51.606Z,1590785751.606 [DAT](INFO): commRate: 800 2020-05-29T20:55:52.007Z,1590785752.007 [DAT](INFO): Starting 2020-05-29T20:55:52.008Z,1590785752.008 [DAT](INFO): commRate: 800 2020-05-29T20:55:52.406Z,1590785752.406 [DAT](INFO): Starting 2020-05-29T20:55:52.407Z,1590785752.407 [DAT](INFO): commRate: 800 2020-05-29T20:55:52.823Z,1590785752.823 [DAT](INFO): Starting 2020-05-29T20:55:52.824Z,1590785752.824 [DAT](INFO): commRate: 800 2020-05-29T20:55:53.227Z,1590785753.227 [DAT](INFO): Starting 2020-05-29T20:55:53.227Z,1590785753.227 [DAT](INFO): commRate: 800 2020-05-29T20:55:53.632Z,1590785753.632 [DAT](INFO): Starting 2020-05-29T20:55:53.632Z,1590785753.632 [DAT](INFO): commRate: 800 2020-05-29T20:55:54.051Z,1590785754.051 [DAT](INFO): Starting 2020-05-29T20:55:54.051Z,1590785754.051 [DAT](INFO): commRate: 800 2020-05-29T20:55:54.426Z,1590785754.426 [DAT](INFO): Starting 2020-05-29T20:55:54.427Z,1590785754.427 [DAT](INFO): commRate: 800 2020-05-29T20:55:54.831Z,1590785754.831 [DAT](INFO): Starting 2020-05-29T20:55:54.832Z,1590785754.832 [DAT](INFO): commRate: 800 2020-05-29T20:55:55.245Z,1590785755.245 [DAT](INFO): Starting 2020-05-29T20:55:55.246Z,1590785755.246 [DAT](INFO): commRate: 800 2020-05-29T20:55:55.310Z,1590785755.310 [CommandLine](IMPORTANT): got command stop 2020-05-29T20:55:55.310Z,1590785755.310 [CommandLine](IMPORTANT): Scheduling is paused 2020-05-29T20:55:55.310Z,1590785755.310 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2020-05-29T20:55:55.637Z,1590785755.637 [DAT](INFO): Starting 2020-05-29T20:55:55.637Z,1590785755.637 [DAT](INFO): commRate: 800 2020-05-29T20:55:56.041Z,1590785756.041 [DAT](INFO): Starting 2020-05-29T20:55:56.042Z,1590785756.042 [DAT](INFO): commRate: 800 2020-05-29T20:55:56.454Z,1590785756.454 [DAT](INFO): Starting 2020-05-29T20:55:56.455Z,1590785756.455 [DAT](INFO): commRate: 800 2020-05-29T20:55:56.856Z,1590785756.856 [DAT](INFO): Starting 2020-05-29T20:55:56.856Z,1590785756.856 [DAT](INFO): commRate: 800 2020-05-29T20:55:57.253Z,1590785757.253 [DAT](INFO): Starting 2020-05-29T20:55:57.254Z,1590785757.254 [DAT](INFO): commRate: 800 2020-05-29T20:55:57.366Z,1590785757.366 [CommandLine](IMPORTANT): got command quit 2020-05-29T20:55:57.657Z,1590785757.657 [DAT](INFO): Starting 2020-05-29T20:55:57.658Z,1590785757.658 [DAT](INFO): commRate: 800 2020-05-29T20:55:58.075Z,1590785758.075 [DAT](INFO): Starting 2020-05-29T20:55:58.075Z,1590785758.075 [DAT](INFO): commRate: 800 2020-05-29T20:55:58.369Z,1590785758.369 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-29T20:55:58.369Z,1590785758.369 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:58.441Z,1590785758.441 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-05-29T20:55:58.441Z,1590785758.441 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:58.442Z,1590785758.442 [CommandLine](INFO): Join timeout helper Thread ID is 8928 2020-05-29T20:55:58.451Z,1590785758.451 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-05-29T20:55:58.451Z,1590785758.451 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:58.452Z,1590785758.452 [NavChartDb](INFO): Join timeout helper Thread ID is 8929 2020-05-29T20:55:58.481Z,1590785758.481 [DAT](INFO): Starting 2020-05-29T20:55:58.482Z,1590785758.482 [DAT](INFO): commRate: 800 2020-05-29T20:55:58.802Z,1590785758.802 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-29T20:55:58.803Z,1590785758.803 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:58.817Z,1590785758.817 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-05-29T20:55:58.817Z,1590785758.817 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:58.817Z,1590785758.817 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8930 2020-05-29T20:55:58.885Z,1590785758.885 [DAT](INFO): Starting 2020-05-29T20:55:58.886Z,1590785758.886 [DAT](INFO): commRate: 800 2020-05-29T20:55:58.973Z,1590785758.973 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-29T20:55:58.974Z,1590785758.974 [WetLabsBB2FL](INFO): Powering down 2020-05-29T20:55:58.974Z,1590785758.974 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:58.985Z,1590785758.985 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-05-29T20:55:58.985Z,1590785758.985 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:58.985Z,1590785758.985 [Radio_Surface](INFO): Join timeout helper Thread ID is 8931 2020-05-29T20:55:59.181Z,1590785759.181 [Radio_Surface](INFO): Powering down 2020-05-29T20:55:59.182Z,1590785759.182 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-29T20:55:59.182Z,1590785759.182 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.197Z,1590785759.197 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-05-29T20:55:59.197Z,1590785759.197 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.197Z,1590785759.197 [DataOverHttps](INFO): Join timeout helper Thread ID is 8932 2020-05-29T20:55:59.287Z,1590785759.287 [DAT](INFO): Starting 2020-05-29T20:55:59.288Z,1590785759.288 [DAT](INFO): commRate: 800 2020-05-29T20:55:59.433Z,1590785759.433 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-29T20:55:59.433Z,1590785759.433 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.442Z,1590785759.442 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-05-29T20:55:59.442Z,1590785759.442 [logger ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.442Z,1590785759.442 [logger](INFO): Join timeout helper Thread ID is 8933 2020-05-29T20:55:59.453Z,1590785759.453 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-29T20:55:59.453Z,1590785759.453 [logger ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.461Z,1590785759.461 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-05-29T20:55:59.462Z,1590785759.462 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.462Z,1590785759.462 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-05-29T20:55:59.462Z,1590785759.462 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.462Z,1590785759.462 [controlThread](INFO): Join timeout helper Thread ID is 8934 2020-05-29T20:55:59.653Z,1590785759.653 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-29T20:55:59.653Z,1590785759.653 [controlThread](DEBUG): Uninitializing ControlThread 2020-05-29T20:55:59.654Z,1590785759.654 [NAL9602](INFO): Powering down 2020-05-29T20:55:59.655Z,1590785759.655 [DAT](INFO): uninitialize 2020-05-29T20:55:59.655Z,1590785759.655 [DAT](INFO): Powering down 2020-05-29T20:55:59.827Z,1590785759.827 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-05-29T20:55:59.828Z,1590785759.828 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-05-29T20:55:59.829Z,1590785759.829 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-05-29T20:55:59.830Z,1590785759.830 [MissionManager](INFO): Uninitializing Mission Default 2020-05-29T20:55:59.833Z,1590785759.833 [MissionManager](INFO): Uninitializing Mission Startup 2020-05-29T20:55:59.834Z,1590785759.834 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-05-29T20:55:59.835Z,1590785759.835 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-05-29T20:55:59.835Z,1590785759.835 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-05-29T20:55:59.835Z,1590785759.835 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-05-29T20:55:59.836Z,1590785759.836 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-05-29T20:55:59.836Z,1590785759.836 [BuoyancyServo](INFO): Powering down 2020-05-29T20:55:59.849Z,1590785759.849 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-05-29T20:55:59.849Z,1590785759.849 [ElevatorServo](INFO): Powering down 2020-05-29T20:55:59.850Z,1590785759.850 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-05-29T20:55:59.850Z,1590785759.850 [MassServo](INFO): Powering down 2020-05-29T20:55:59.851Z,1590785759.851 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-05-29T20:55:59.851Z,1590785759.851 [RudderServo](INFO): Powering down 2020-05-29T20:55:59.852Z,1590785759.852 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-05-29T20:55:59.852Z,1590785759.852 [ThrusterServo](INFO): Powering down 2020-05-29T20:55:59.853Z,1590785759.853 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-05-29T20:55:59.854Z,1590785759.854 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-05-29T20:55:59.854Z,1590785759.854 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-05-29T20:55:59.854Z,1590785759.854 [CBIT](DEBUG): Powering off loads. 2020-05-29T20:55:59.865Z,1590785759.865 [CBIT](DEBUG): Disabling WDT. 2020-05-29T20:55:59.877Z,1590785759.877 [CBIT](DEBUG): Opening all GF detection circuits. 2020-05-29T20:55:59.878Z,1590785759.878 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:55:59.995Z,1590785759.995 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:56:00.003Z,1590785760.003 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:56:00.063Z,1590785760.063 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:56:00.098Z,1590785760.098 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-29T20:56:00.187Z,1590785760.187 [logger ThreadHandler](INFO): Thread cancelled.