2020-09-23T17:43:55.508Z,1600883035.508 [Supervisor](DEBUG): Initializing supervisor.
2020-09-23T17:43:55.511Z,1600883035.511 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-23T17:43:55.511Z,1600883035.511 [SyncHandler](INFO): Protected caller Thread ID is 835
2020-09-23T17:43:55.512Z,1600883035.512 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-23T17:43:55.513Z,1600883035.513 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-23T17:43:55.513Z,1600883035.513 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2020-09-23T17:43:55.516Z,1600883035.516 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-23T17:43:55.530Z,1600883035.530 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-23T17:43:55.531Z,1600883035.531 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-23T17:43:55.531Z,1600883035.531 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2020-09-23T17:43:55.532Z,1600883035.532 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-23T17:43:55.533Z,1600883035.533 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-23T17:43:55.533Z,1600883035.533 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2020-09-23T17:43:55.536Z,1600883035.536 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-23T17:43:55.537Z,1600883035.537 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-23T17:43:55.541Z,1600883035.541 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-23T17:43:55.635Z,1600883035.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-23T17:43:55.637Z,1600883035.637 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-23T17:43:56.171Z,1600883036.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-23T17:43:56.172Z,1600883036.172 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-23T17:43:56.360Z,1600883036.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-23T17:43:56.362Z,1600883036.362 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-23T17:43:56.442Z,1600883036.442 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-23T17:43:56.542Z,1600883036.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-23T17:43:56.544Z,1600883036.544 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-23T17:43:56.625Z,1600883036.625 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-23T17:43:56.722Z,1600883036.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-23T17:43:56.724Z,1600883036.724 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-23T17:43:57.005Z,1600883037.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-23T17:43:57.007Z,1600883037.007 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-23T17:43:57.469Z,1600883037.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-23T17:43:57.469Z,1600883037.469 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-23T17:43:57.610Z,1600883037.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-23T17:43:57.611Z,1600883037.611 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-23T17:43:57.789Z,1600883037.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-23T17:43:57.790Z,1600883037.790 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-23T17:43:58.176Z,1600883038.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-23T17:43:58.176Z,1600883038.176 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-23T17:43:58.387Z,1600883038.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-23T17:43:58.388Z,1600883038.388 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-23T17:43:58.581Z,1600883038.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-23T17:43:58.583Z,1600883038.583 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-23T17:43:58.955Z,1600883038.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-23T17:43:58.956Z,1600883038.956 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-23T17:43:59.908Z,1600883039.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-23T17:43:59.910Z,1600883039.910 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-09-23T17:43:59.913Z,1600883039.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-09-23T17:44:00.038Z,1600883040.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-09-23T17:44:00.189Z,1600883040.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-09-23T17:44:00.298Z,1600883040.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-09-23T17:44:00.382Z,1600883040.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-09-23T17:44:00.475Z,1600883040.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-09-23T17:44:00.658Z,1600883040.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-09-23T17:44:00.881Z,1600883040.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-23T17:44:00.881Z,1600883040.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-09-23T17:44:00.975Z,1600883040.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-09-23T17:44:01.071Z,1600883041.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-09-23T17:44:01.194Z,1600883041.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-09-23T17:44:01.292Z,1600883041.292 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-09-23T17:44:01.297Z,1600883041.297 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-23T17:44:01.397Z,1600883041.397 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-23T17:44:01.398Z,1600883041.398 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-23T17:44:01.462Z,1600883041.462 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-23T17:44:01.571Z,1600883041.571 [VerticalControl] Loaded
2020-09-23T17:44:01.572Z,1600883041.572 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-23T17:44:01.572Z,1600883041.572 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-23T17:44:01.650Z,1600883041.650 [HorizontalControl] Loaded
2020-09-23T17:44:01.650Z,1600883041.650 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-23T17:44:01.651Z,1600883041.651 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-23T17:44:01.656Z,1600883041.656 [SpeedControl] Loaded
2020-09-23T17:44:01.657Z,1600883041.657 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-23T17:44:01.657Z,1600883041.657 [LoopControl](DEBUG): Construct LoopControl.
2020-09-23T17:44:01.658Z,1600883041.658 [LoopControl] Loaded
2020-09-23T17:44:01.658Z,1600883041.658 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-23T17:44:01.658Z,1600883041.658 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-23T17:44:01.659Z,1600883041.659 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-23T17:44:01.714Z,1600883041.714 [DepthRateCalculator] Loaded
2020-09-23T17:44:01.714Z,1600883041.714 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-23T17:44:01.720Z,1600883041.720 [PitchRateCalculator] Loaded
2020-09-23T17:44:01.720Z,1600883041.720 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-23T17:44:01.735Z,1600883041.735 [SpeedCalculator] Loaded
2020-09-23T17:44:01.736Z,1600883041.736 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-23T17:44:01.756Z,1600883041.756 [TempGradientCalculator] Loaded
2020-09-23T17:44:01.756Z,1600883041.756 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-23T17:44:01.761Z,1600883041.761 [YawRateCalculator] Loaded
2020-09-23T17:44:01.761Z,1600883041.761 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-23T17:44:01.790Z,1600883041.790 [ElevatorOffsetCalculator] Loaded
2020-09-23T17:44:01.790Z,1600883041.790 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-23T17:44:01.790Z,1600883041.790 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-23T17:44:01.791Z,1600883041.791 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-23T17:44:01.832Z,1600883041.832 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-23T17:44:01.833Z,1600883041.833 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-23T17:44:01.963Z,1600883041.963 [Module Loader](CRITICAL): Could not find element HFRadarModelCalc.loadAtStartup
2020-09-23T17:44:01.964Z,1600883041.964 [Module Loader](CRITICAL): Could not find element HFRadarCompactModelForecaster.loadAtStartup
2020-09-23T17:44:01.964Z,1600883041.964 [Module Loader](CRITICAL): Could not find element HFRCMSpaceInterpolator.loadAtStartup
2020-09-23T17:44:01.964Z,1600883041.964 [Module Loader](CRITICAL): Could not find element HFRCMTimeInterpolator.loadAtStartup
2020-09-23T17:44:01.964Z,1600883041.964 [Module Loader](CRITICAL): Could not find element HFRCMReconstructedInterpolator.loadAtStartup
2020-09-23T17:44:01.964Z,1600883041.964 [Module Loader](CRITICAL): Could not find element HFRCMSurfaceCurrentAtVehicleLocation.loadAtStartup
2020-09-23T17:44:01.965Z,1600883041.965 [Module Loader](CRITICAL): Could not find element HFRCMVirtualSurfaceDrifter.loadAtStartup
2020-09-23T17:44:01.965Z,1600883041.965 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-09-23T17:44:01.966Z,1600883041.966 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-23T17:44:02.304Z,1600883042.304 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-23T17:44:02.305Z,1600883042.305 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-23T17:44:02.431Z,1600883042.431 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-23T17:44:02.432Z,1600883042.432 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-23T17:44:02.959Z,1600883042.959 [Module Loader](CRITICAL): Could not find element AHRS_sp3003D.loadAtStartup
2020-09-23T17:44:03.006Z,1600883043.006 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-09-23T17:44:03.011Z,1600883043.011 [AHRS_M2](INFO): created writer for : platform_orientation
2020-09-23T17:44:03.012Z,1600883043.012 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-09-23T17:44:03.018Z,1600883043.018 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-09-23T17:44:03.018Z,1600883043.018 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-09-23T17:44:03.023Z,1600883043.023 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-09-23T17:44:03.023Z,1600883043.023 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-09-23T17:44:03.029Z,1600883043.029 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-09-23T17:44:03.143Z,1600883043.143 [AHRS_M2] Loaded
2020-09-23T17:44:03.143Z,1600883043.143 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-23T17:44:03.143Z,1600883043.143 [Module Loader](CRITICAL): Could not find element AHRS_3DMGX3.loadAtStartup
2020-09-23T17:44:03.143Z,1600883043.143 [Module Loader](CRITICAL): Could not find element Batt_Ocean_Server.loadAtStartup
2020-09-23T17:44:03.291Z,1600883043.291 [DataOverHttps] Loaded
2020-09-23T17:44:03.292Z,1600883043.292 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-23T17:44:03.293Z,1600883043.293 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-09-23T17:44:03.293Z,1600883043.293 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 920
2020-09-23T17:44:03.324Z,1600883043.324 [Depth_Keller] Loaded
2020-09-23T17:44:03.324Z,1600883043.324 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-23T17:44:03.329Z,1600883043.329 [DropWeight] Loaded
2020-09-23T17:44:03.329Z,1600883043.329 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-09-23T17:44:03.444Z,1600883043.444 [NAL9602] Loaded
2020-09-23T17:44:03.444Z,1600883043.444 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-23T17:44:03.492Z,1600883043.492 [Onboard] Loaded
2020-09-23T17:44:03.493Z,1600883043.493 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-23T17:44:03.494Z,1600883043.494 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-09-23T17:44:03.494Z,1600883043.494 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 921
2020-09-23T17:44:03.513Z,1600883043.513 [Radio_Surface] Loaded
2020-09-23T17:44:03.513Z,1600883043.513 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-23T17:44:03.514Z,1600883043.514 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-09-23T17:44:03.515Z,1600883043.515 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 922
2020-09-23T17:44:03.588Z,1600883043.588 [RDI_Pathfinder] Loaded
2020-09-23T17:44:03.588Z,1600883043.588 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-09-23T17:44:03.739Z,1600883043.739 [DAT] Loaded
2020-09-23T17:44:03.739Z,1600883043.739 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-09-23T17:44:03.739Z,1600883043.739 [Module Loader](CRITICAL): Could not find element Rowe_600LCM.loadAtStartup
2020-09-23T17:44:05.690Z,1600883045.690 [BPC1] Loaded
2020-09-23T17:44:05.691Z,1600883045.691 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-23T17:44:05.691Z,1600883045.691 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-23T17:44:05.692Z,1600883045.692 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-23T17:44:05.826Z,1600883045.826 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-23T17:44:05.826Z,1600883045.826 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-23T17:44:05.846Z,1600883045.846 [NavChart] Loaded
2020-09-23T17:44:05.847Z,1600883045.847 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-23T17:44:05.850Z,1600883045.850 [UniversalFixResidualReporter] Loaded
2020-09-23T17:44:05.851Z,1600883045.851 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-23T17:44:05.851Z,1600883045.851 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-23T17:44:05.852Z,1600883045.852 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-23T17:44:06.014Z,1600883046.014 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-23T17:44:06.025Z,1600883046.025 [SBIT] Loaded
2020-09-23T17:44:06.025Z,1600883046.025 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-23T17:44:06.026Z,1600883046.026 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-23T17:44:06.038Z,1600883046.038 [IBIT] Loaded
2020-09-23T17:44:06.038Z,1600883046.038 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-23T17:44:06.042Z,1600883046.042 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-23T17:44:06.177Z,1600883046.177 [CBIT] Loaded
2020-09-23T17:44:06.177Z,1600883046.177 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-23T17:44:06.178Z,1600883046.178 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-23T17:44:06.178Z,1600883046.178 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-23T17:44:06.326Z,1600883046.326 [BuoyancyServo] Loaded
2020-09-23T17:44:06.326Z,1600883046.326 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-23T17:44:06.398Z,1600883046.398 [ElevatorServo] Loaded
2020-09-23T17:44:06.398Z,1600883046.398 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-23T17:44:06.426Z,1600883046.426 [MassServo] Loaded
2020-09-23T17:44:06.426Z,1600883046.426 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-23T17:44:06.452Z,1600883046.452 [RudderServo] Loaded
2020-09-23T17:44:06.453Z,1600883046.453 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-23T17:44:06.478Z,1600883046.478 [ThrusterServo] Loaded
2020-09-23T17:44:06.479Z,1600883046.479 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-23T17:44:06.479Z,1600883046.479 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-23T17:44:06.480Z,1600883046.480 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-23T17:44:06.510Z,1600883046.510 [Module Loader](CRITICAL): Could not find element AsyncPiEstimator.loadAtStartup
2020-09-23T17:44:06.510Z,1600883046.510 [Module Loader](CRITICAL): Could not find element LcmListener.loadAtStartup
2020-09-23T17:44:06.510Z,1600883046.510 [Module Loader](CRITICAL): Could not find element LcmPublisher.loadAtStartup
2020-09-23T17:44:06.511Z,1600883046.511 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-23T17:44:06.511Z,1600883046.511 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-23T17:44:06.771Z,1600883046.771 [Module Loader](CRITICAL): Could not find element BackSeatDriver.loadAtStartup
2020-09-23T17:44:06.800Z,1600883046.800 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-09-23T17:44:06.805Z,1600883046.805 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-09-23T17:44:06.805Z,1600883046.805 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-09-23T17:44:06.810Z,1600883046.810 [CTD_Seabird](INFO): created writer for : depth
2020-09-23T17:44:06.811Z,1600883046.811 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-09-23T17:44:06.816Z,1600883046.816 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-09-23T17:44:06.816Z,1600883046.816 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-09-23T17:44:06.822Z,1600883046.822 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-09-23T17:44:06.822Z,1600883046.822 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-09-23T17:44:06.827Z,1600883046.827 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-09-23T17:44:06.828Z,1600883046.828 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-09-23T17:44:06.833Z,1600883046.833 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-09-23T17:44:06.833Z,1600883046.833 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-09-23T17:44:06.838Z,1600883046.838 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-09-23T17:44:06.865Z,1600883046.865 [CTD_Seabird] Loaded
2020-09-23T17:44:06.866Z,1600883046.866 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-23T17:44:06.867Z,1600883046.867 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B044E0
2020-09-23T17:44:06.867Z,1600883046.867 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924
2020-09-23T17:44:06.867Z,1600883046.867 [Module Loader](CRITICAL): Could not find element CTD_SeabirdLCM.loadAtStartup
2020-09-23T17:44:06.868Z,1600883046.868 [Module Loader](CRITICAL): Could not find element ISUS.loadAtStartup
2020-09-23T17:44:06.868Z,1600883046.868 [Module Loader](CRITICAL): Could not find element Turner_Cyclops_rhodamine.loadAtStartup
2020-09-23T17:44:06.868Z,1600883046.868 [Module Loader](CRITICAL): Could not find element Turbulence_NPS.loadAtStartup
2020-09-23T17:44:06.889Z,1600883046.889 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-09-23T17:44:06.889Z,1600883046.889 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-09-23T17:44:06.893Z,1600883046.893 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-09-23T17:44:06.894Z,1600883046.894 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-09-23T17:44:06.898Z,1600883046.898 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-09-23T17:44:06.898Z,1600883046.898 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-09-23T17:44:06.902Z,1600883046.902 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-09-23T17:44:06.902Z,1600883046.902 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-09-23T17:44:06.907Z,1600883046.907 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-09-23T17:44:06.907Z,1600883046.907 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-09-23T17:44:06.911Z,1600883046.911 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-09-23T17:44:06.911Z,1600883046.911 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-09-23T17:44:06.915Z,1600883046.915 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-09-23T17:44:06.915Z,1600883046.915 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-09-23T17:44:06.919Z,1600883046.919 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.924Z,1600883046.924 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.924Z,1600883046.924 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.925Z,1600883046.925 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.929Z,1600883046.929 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.929Z,1600883046.929 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.933Z,1600883046.933 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.933Z,1600883046.933 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T17:44:06.937Z,1600883046.937 [WetLabsBB2FL] Loaded
2020-09-23T17:44:06.938Z,1600883046.938 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-09-23T17:44:06.939Z,1600883046.939 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B344E0
2020-09-23T17:44:06.939Z,1600883046.939 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925
2020-09-23T17:44:06.940Z,1600883046.940 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-23T17:44:06.943Z,1600883046.943 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-23T17:44:06.944Z,1600883046.944 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-23T17:44:06.951Z,1600883046.951 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-23T17:44:06.952Z,1600883046.952 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0
2020-09-23T17:44:06.952Z,1600883046.952 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2020-09-23T17:44:06.957Z,1600883046.957 [Supervisor](INFO): Main Thread ID is 829
2020-09-23T17:44:06.957Z,1600883046.957 [Supervisor](DEBUG): Running supervisor.
2020-09-23T17:44:06.957Z,1600883046.957 [CommandLine ThreadHandler](INFO): Handler Thread ID is 927
2020-09-23T17:44:06.960Z,1600883046.960 [controlThread ThreadHandler](INFO): Handler Thread ID is 928
2020-09-23T17:44:06.960Z,1600883046.960 [controlThread](DEBUG): Initializing ControlThread
2020-09-23T17:44:06.961Z,1600883046.961 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-23T17:44:06.962Z,1600883046.962 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-23T17:44:06.963Z,1600883046.963 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-23T17:44:06.964Z,1600883046.964 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-23T17:44:06.964Z,1600883046.964 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-23T17:44:06.964Z,1600883046.964 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-23T17:44:06.965Z,1600883046.965 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-23T17:44:06.965Z,1600883046.965 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-23T17:44:06.966Z,1600883046.966 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-23T17:44:06.966Z,1600883046.966 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-23T17:44:06.970Z,1600883046.970 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-23T17:44:06.971Z,1600883046.971 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-23T17:44:06.971Z,1600883046.971 [SBIT](INFO): Initialize SBIT Component.
2020-09-23T17:44:06.972Z,1600883046.972 [SBIT](IMPORTANT): git: 2020-09-22a
2020-09-23T17:44:06.972Z,1600883046.972 [SBIT](INFO): git hash: 88a1949af2713e823806f4c807492f345e665d2a
2020-09-23T17:44:06.972Z,1600883046.972 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-23T17:44:06.973Z,1600883046.973 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-09-23T17:44:06.974Z,1600883046.974 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-09-23T17:44:06.975Z,1600883046.975 [IBIT](INFO): Initialize IBIT Component.
2020-09-23T17:44:06.976Z,1600883046.976 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-23T17:44:06.977Z,1600883046.977 [logger ThreadHandler](INFO): Handler Thread ID is 929
2020-09-23T17:44:06.988Z,1600883046.988 [CBIT](DEBUG): Initialized mux pins.
2020-09-23T17:44:06.988Z,1600883046.988 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2020-09-23T17:44:06.988Z,1600883046.988 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-23T17:44:06.996Z,1600883046.996 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 930
2020-09-23T17:44:06.997Z,1600883046.997 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-23T17:44:07.008Z,1600883047.008 [Onboard ThreadHandler](INFO): Handler Thread ID is 931
2020-09-23T17:44:07.013Z,1600883047.013 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2020-09-23T17:44:07.013Z,1600883047.013 [CBIT](DEBUG): Initializing heartbeat.
2020-09-23T17:44:07.029Z,1600883047.029 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 932
2020-09-23T17:44:07.048Z,1600883047.048 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 933
2020-09-23T17:44:07.049Z,1600883047.049 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-23T17:44:07.053Z,1600883047.053 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 935
2020-09-23T17:44:07.054Z,1600883047.054 [WetLabsBB2FL](INFO): Powering down
2020-09-23T17:44:07.084Z,1600883047.084 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-23T17:44:07.084Z,1600883047.084 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-23T17:44:07.120Z,1600883047.120 [CBIT](DEBUG): Backplane powered.
2020-09-23T17:44:07.172Z,1600883047.172 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-23T17:44:07.183Z,1600883047.183 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 936
2020-09-23T17:44:07.293Z,1600883047.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-23T17:44:07.293Z,1600883047.293 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-23T17:44:07.293Z,1600883047.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-23T17:44:07.293Z,1600883047.293 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-23T17:44:07.294Z,1600883047.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-23T17:44:07.294Z,1600883047.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-23T17:44:07.294Z,1600883047.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-23T17:44:07.294Z,1600883047.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-23T17:44:07.294Z,1600883047.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-23T17:44:07.294Z,1600883047.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-23T17:44:07.295Z,1600883047.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-23T17:44:07.295Z,1600883047.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-23T17:44:07.295Z,1600883047.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-23T17:44:07.295Z,1600883047.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-23T17:44:07.295Z,1600883047.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-23T17:44:07.295Z,1600883047.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-23T17:44:07.303Z,1600883047.303 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-23T17:44:07.432Z,1600883047.432 [Radio_Surface](INFO): Powering up
2020-09-23T17:44:07.452Z,1600883047.452 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2020-09-23T17:44:07.453Z,1600883047.453 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-23T17:44:07.514Z,1600883047.514 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-23T17:44:07.532Z,1600883047.532 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-23T17:44:07.533Z,1600883047.533 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-23T17:44:07.561Z,1600883047.561 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-23T17:44:07.563Z,1600883047.563 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-23T17:44:07.585Z,1600883047.585 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-23T17:44:07.612Z,1600883047.612 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2020-09-23T17:44:07.616Z,1600883047.616 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,Depth_Keller,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-09-23T17:44:07.628Z,1600883047.628 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-23T17:44:07.741Z,1600883047.741 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-09-23T17:44:07.751Z,1600883047.751 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-09-23T17:44:07.751Z,1600883047.751 [DAT](INFO): Powering up
2020-09-23T17:44:07.751Z,1600883047.751 [DAT](DEBUG): Initializing DAT.
2020-09-23T17:44:07.756Z,1600883047.756 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-09-23T17:44:07.832Z,1600883047.832 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-23T17:44:07.869Z,1600883047.869 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-23T17:44:07.879Z,1600883047.879 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-23T17:44:07.881Z,1600883047.881 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-23T17:44:07.888Z,1600883047.888 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-23T17:44:07.889Z,1600883047.889 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-23T17:44:07.896Z,1600883047.896 [MassServo](DEBUG): Initializing MassServo.
2020-09-23T17:44:07.897Z,1600883047.897 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-23T17:44:07.904Z,1600883047.904 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-23T17:44:07.905Z,1600883047.905 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-23T17:44:07.912Z,1600883047.912 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-23T17:44:07.915Z,1600883047.915 [CommandLine](FAULT): Scheduling is paused
2020-09-23T17:44:07.915Z,1600883047.915 [CBIT](INFO): Critical error at 20200923T174407
2020-09-23T17:44:07.915Z,1600883047.915 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-09-23T17:44:08.061Z,1600883048.061 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-23T17:44:08.756Z,1600883048.756 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-23T17:44:08.756Z,1600883048.756 [RudderServo](FAULT): Rudder failed to initialize
2020-09-23T17:44:08.756Z,1600883048.756 [RudderServo] Communications Fault, FailCount= 1
2020-09-23T17:44:08.756Z,1600883048.756 [RudderServo](ERROR): Communications Fault
2020-09-23T17:44:08.903Z,1600883048.903 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-09-23T17:44:09.073Z,1600883049.073 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-23T17:44:09.073Z,1600883049.073 [RudderServo](INFO): Powering down
2020-09-23T17:44:09.731Z,1600883049.731 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-23T17:44:09.849Z,1600883049.849 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-23T17:44:09.853Z,1600883049.853 [CBIT](INFO): Clearing failed state for component RudderServo
2020-09-23T17:44:09.853Z,1600883049.853 [RudderServo] No Fault, FailCount= 1
2020-09-23T17:44:21.848Z,1600883061.848 [DAT](INFO): commRate: 800
2020-09-23T17:44:23.863Z,1600883063.863 [DAT](INFO): entering command mode
2020-09-23T17:44:24.267Z,1600883064.267 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:24.671Z,1600883064.671 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:25.079Z,1600883065.079 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:25.495Z,1600883065.495 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:25.883Z,1600883065.883 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:26.301Z,1600883066.301 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:26.707Z,1600883066.707 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:27.117Z,1600883067.117 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:27.499Z,1600883067.499 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:27.911Z,1600883067.911 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:28.311Z,1600883068.311 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:28.731Z,1600883068.731 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:29.119Z,1600883069.119 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:29.531Z,1600883069.531 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:29.925Z,1600883069.925 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T17:44:29.925Z,1600883069.925 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-09-23T17:44:29.925Z,1600883069.925 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T17:44:29.926Z,1600883069.926 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:29.992Z,1600883069.992 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T17:44:30.401Z,1600883070.401 [RDI_Pathfinder](INFO): Powering down
2020-09-23T17:44:30.405Z,1600883070.405 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:30.730Z,1600883070.730 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:31.134Z,1600883071.134 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:31.180Z,1600883071.180 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T17:44:31.180Z,1600883071.180 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T17:44:31.548Z,1600883071.548 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:31.942Z,1600883071.942 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:32.346Z,1600883072.346 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:32.753Z,1600883072.753 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:33.158Z,1600883073.158 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:33.558Z,1600883073.558 [NAL9602](INFO): Powering up NAL9602
2020-09-23T17:44:33.563Z,1600883073.563 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:33.970Z,1600883073.970 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:34.366Z,1600883074.366 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:34.775Z,1600883074.775 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:35.174Z,1600883075.174 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:35.578Z,1600883075.578 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:35.981Z,1600883075.981 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:36.391Z,1600883076.391 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:36.790Z,1600883076.790 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:37.194Z,1600883077.194 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:37.597Z,1600883077.597 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:38.006Z,1600883078.006 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:38.405Z,1600883078.405 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:38.817Z,1600883078.817 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:39.218Z,1600883079.218 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T17:44:39.218Z,1600883079.218 [DAT](FAULT): failed to enter command mode
2020-09-23T17:44:39.622Z,1600883079.622 [DAT](INFO): entering command mode
2020-09-23T17:44:40.055Z,1600883080.055 [DAT](INFO): setting verbose to 3
2020-09-23T17:44:40.431Z,1600883080.431 [DAT](INFO): set verbose to 3
2020-09-23T17:44:40.431Z,1600883080.431 [DAT](INFO): setting DatVerbose to 27440
2020-09-23T17:44:40.835Z,1600883080.835 [DAT](INFO): set DatVerbose to 27440
2020-09-23T17:44:40.835Z,1600883080.835 [DAT](INFO): setting transmit power to 8
2020-09-23T17:44:41.239Z,1600883081.239 [DAT](INFO): set transmit power to 8
2020-09-23T17:44:41.239Z,1600883081.239 [DAT](INFO): setting local address to 9
2020-09-23T17:44:41.651Z,1600883081.651 [DAT](INFO): set local address to 9
2020-09-23T17:44:44.469Z,1600883084.469 [NAL9602](INFO): NAL9602 initialized
2020-09-23T17:44:46.555Z,1600883086.555 [SBIT](IMPORTANT): Beginning Startup BIT
2020-09-23T17:44:46.563Z,1600883086.563 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-23T17:44:53.762Z,1600883093.762 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T17:44:53.762Z,1600883093.762 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-23T17:44:53.762Z,1600883093.762 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T17:44:53.872Z,1600883093.872 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T17:44:54.236Z,1600883094.236 [RDI_Pathfinder](INFO): Powering down
2020-09-23T17:44:55.088Z,1600883095.088 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T17:44:55.088Z,1600883095.088 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-23T17:44:57.519Z,1600883097.519 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.037099
CHAN A1 (24V): 0.036477
CHAN A2 (12V): -0.005128
CHAN A3 (5V): -0.002258
CHAN B0 (3.3V): 0.000285
CHAN B1 (3.15aV): -0.000408
CHAN B2 (3.15bV): -0.000387
CHAN B3 (GND): 0.001965
OPEN: -0.003557
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-23T17:45:17.602Z,1600883117.602 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T17:45:17.602Z,1600883117.602 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-09-23T17:45:17.602Z,1600883117.602 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T17:45:17.768Z,1600883117.768 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T17:45:17.769Z,1600883117.769 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T17:45:18.072Z,1600883118.072 [RDI_Pathfinder](INFO): Powering down
2020-09-23T17:45:18.091Z,1600883118.091 [CBIT](INFO): Critical error at 20200923T174517
2020-09-23T17:45:40.266Z,1600883140.266 [SBIT](IMPORTANT): SBIT PASSED
2020-09-23T17:45:40.266Z,1600883140.266 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-09-23T17:45:40.649Z,1600883140.649 [MissionManager](IMPORTANT): Started mission Startup
2020-09-23T17:45:40.649Z,1600883140.649 [Startup] Running Loop=1
2020-09-23T17:45:40.649Z,1600883140.649 [Startup](DEBUG): Aggregate::initialize Startup
2020-09-23T17:45:40.649Z,1600883140.649 [Startup:A.GoToSurface] Running Loop=1
2020-09-23T17:45:40.649Z,1600883140.649 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-23T17:45:40.650Z,1600883140.650 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-23T17:45:40.651Z,1600883140.651 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-23T17:45:40.651Z,1600883140.651 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-23T17:45:40.651Z,1600883140.651 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-23T17:45:40.652Z,1600883140.652 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-23T17:45:40.653Z,1600883140.653 [Startup:StartupSatComms] Running Loop=1
2020-09-23T17:45:40.653Z,1600883140.653 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-09-23T17:45:40.653Z,1600883140.653 [Startup:StartupSatComms:A] Running Loop=1
2020-09-23T17:45:41.049Z,1600883141.049 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-09-23T17:46:40.833Z,1600883200.833 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-23T17:45:40.7Z
2020-09-23T17:46:40.833Z,1600883200.833 [Startup:StartupSatComms:A] Stopped
2020-09-23T17:46:40.833Z,1600883200.833 [Startup:StartupSatComms:B] Running Loop=1
2020-09-23T17:46:41.307Z,1600883201.307 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-23T17:46:41.308Z,1600883201.308 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:41.656Z,1600883201.656 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:42.069Z,1600883202.069 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:42.486Z,1600883202.486 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:42.873Z,1600883202.873 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:43.277Z,1600883203.277 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:43.685Z,1600883203.685 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:44.065Z,1600883204.065 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:44.477Z,1600883204.477 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:44.886Z,1600883204.886 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:45.277Z,1600883205.277 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:45.689Z,1600883205.689 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:46.089Z,1600883206.089 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:46.501Z,1600883206.501 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:46.893Z,1600883206.893 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:47.301Z,1600883207.301 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:47.721Z,1600883207.721 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:48.121Z,1600883208.121 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:48.532Z,1600883208.532 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:48.925Z,1600883208.925 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:49.333Z,1600883209.333 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:49.731Z,1600883209.731 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:50.120Z,1600883210.120 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:50.524Z,1600883210.524 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:50.933Z,1600883210.933 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:51.453Z,1600883211.453 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:51.781Z,1600883211.781 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:52.173Z,1600883212.173 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:52.584Z,1600883212.584 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:52.977Z,1600883212.977 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:53.373Z,1600883213.373 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:53.784Z,1600883213.784 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:53.968Z,1600883213.968 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005760
2020-09-23T17:46:54.181Z,1600883214.181 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:54.633Z,1600883214.633 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:55.022Z,1600883215.022 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:55.418Z,1600883215.418 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:55.797Z,1600883215.797 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:56.191Z,1600883216.191 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:56.597Z,1600883216.597 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:57.049Z,1600883217.049 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:57.425Z,1600883217.425 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:57.829Z,1600883217.829 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:58.295Z,1600883218.295 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:58.665Z,1600883218.665 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:59.097Z,1600883219.097 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:59.509Z,1600883219.509 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:46:59.861Z,1600883219.861 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:00.279Z,1600883220.279 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:00.673Z,1600883220.673 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:01.077Z,1600883221.077 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:01.525Z,1600883221.525 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:01.909Z,1600883221.909 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:02.291Z,1600883222.291 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:02.701Z,1600883222.701 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:03.113Z,1600883223.113 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:03.521Z,1600883223.521 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:03.917Z,1600883223.917 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:04.394Z,1600883224.394 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:04.713Z,1600883224.713 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200922T225638/Courier0333.lzma
2020-09-23T17:47:04.777Z,1600883224.777 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:05.136Z,1600883225.136 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:05.551Z,1600883225.551 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:05.715Z,1600883225.715 [DataOverHttps](INFO): Moved sent file to Logs/20200922T225638/Courier0333.lzma.bak
2020-09-23T17:47:05.715Z,1600883225.715 [DataOverHttps](INFO): SBD MOMSN=12665824
2020-09-23T17:47:05.925Z,1600883225.925 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:06.332Z,1600883226.332 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:06.786Z,1600883226.786 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:07.132Z,1600883227.132 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-09-23T17:47:07.132Z,1600883227.132 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T17:47:07.133Z,1600883227.133 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:07.147Z,1600883227.147 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T17:47:07.556Z,1600883227.556 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:07.565Z,1600883227.565 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T17:47:07.565Z,1600883227.565 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-09-23T17:47:07.965Z,1600883227.965 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:08.414Z,1600883228.414 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:08.791Z,1600883228.791 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:09.185Z,1600883229.185 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:09.589Z,1600883229.589 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:09.973Z,1600883229.973 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:10.385Z,1600883230.385 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:10.802Z,1600883230.802 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:11.179Z,1600883231.179 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:11.581Z,1600883231.581 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:11.987Z,1600883231.987 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:12.386Z,1600883232.386 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:12.789Z,1600883232.789 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:13.197Z,1600883233.197 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:13.604Z,1600883233.604 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:14.025Z,1600883234.025 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:14.432Z,1600883234.432 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:14.829Z,1600883234.829 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:15.020Z,1600883235.020 [CommandLine](IMPORTANT): got command restart system
2020-09-23T17:47:15.235Z,1600883235.235 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:15.621Z,1600883235.621 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:16.061Z,1600883236.061 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:16.444Z,1600883236.444 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:16.897Z,1600883236.897 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:17.137Z,1600883237.137 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:17.137Z,1600883237.137 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.148Z,1600883237.148 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-23T17:47:17.148Z,1600883237.148 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.149Z,1600883237.149 [CommandLine](INFO): Join timeout helper Thread ID is 1029
2020-09-23T17:47:17.168Z,1600883237.168 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-23T17:47:17.168Z,1600883237.168 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.168Z,1600883237.168 [NavChartDb](INFO): Join timeout helper Thread ID is 1030
2020-09-23T17:47:17.269Z,1600883237.269 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:17.312Z,1600883237.312 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:17.312Z,1600883237.312 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.320Z,1600883237.320 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-09-23T17:47:17.320Z,1600883237.320 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.320Z,1600883237.320 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1031
2020-09-23T17:47:17.605Z,1600883237.605 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:17.605Z,1600883237.605 [WetLabsBB2FL](INFO): Powering down
2020-09-23T17:47:17.606Z,1600883237.606 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.657Z,1600883237.657 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-09-23T17:47:17.657Z,1600883237.657 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.658Z,1600883237.658 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1032
2020-09-23T17:47:17.787Z,1600883237.787 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:17.824Z,1600883237.824 [CTD_Seabird](INFO): Powering down
2020-09-23T17:47:17.836Z,1600883237.836 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:17.836Z,1600883237.836 [CTD_Seabird](INFO): Powering down
2020-09-23T17:47:17.848Z,1600883237.848 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.860Z,1600883237.860 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-23T17:47:17.860Z,1600883237.860 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:17.860Z,1600883237.860 [Radio_Surface](INFO): Join timeout helper Thread ID is 1033
2020-09-23T17:47:18.053Z,1600883238.053 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:18.144Z,1600883238.144 [Radio_Surface](INFO): Powering down
2020-09-23T17:47:18.145Z,1600883238.145 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:18.145Z,1600883238.145 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:18.149Z,1600883238.149 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-23T17:47:18.149Z,1600883238.149 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:18.149Z,1600883238.149 [Onboard](INFO): Join timeout helper Thread ID is 1034
2020-09-23T17:47:18.471Z,1600883238.471 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:18.869Z,1600883238.869 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:19.273Z,1600883239.273 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:19.669Z,1600883239.669 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:20.087Z,1600883240.087 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:20.481Z,1600883240.481 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:20.881Z,1600883240.881 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:21.293Z,1600883241.293 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:21.757Z,1600883241.757 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:22.093Z,1600883242.093 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:22.208Z,1600883242.208 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:22.208Z,1600883242.208 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.216Z,1600883242.216 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-23T17:47:22.217Z,1600883242.217 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.217Z,1600883242.217 [DataOverHttps](INFO): Join timeout helper Thread ID is 1035
2020-09-23T17:47:22.400Z,1600883242.400 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:22.403Z,1600883242.403 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.416Z,1600883242.416 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-23T17:47:22.417Z,1600883242.417 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.417Z,1600883242.417 [logger](INFO): Join timeout helper Thread ID is 1036
2020-09-23T17:47:22.460Z,1600883242.460 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:22.460Z,1600883242.460 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.481Z,1600883242.481 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-23T17:47:22.481Z,1600883242.481 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.481Z,1600883242.481 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-23T17:47:22.481Z,1600883242.481 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.481Z,1600883242.481 [controlThread](INFO): Join timeout helper Thread ID is 1037
2020-09-23T17:47:22.502Z,1600883242.502 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:22.512Z,1600883242.512 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-23T17:47:22.512Z,1600883242.512 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-23T17:47:22.513Z,1600883242.513 [AHRS_M2](INFO): Powering down
2020-09-23T17:47:22.585Z,1600883242.585 [NAL9602](INFO): Powering down
2020-09-23T17:47:22.586Z,1600883242.586 [RDI_Pathfinder](INFO): Powering down
2020-09-23T17:47:22.587Z,1600883242.587 [DAT](INFO): Powering down
2020-09-23T17:47:22.705Z,1600883242.705 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-23T17:47:22.706Z,1600883242.706 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-23T17:47:22.707Z,1600883242.707 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-23T17:47:22.707Z,1600883242.707 [MissionManager](INFO): Uninitializing Mission Default
2020-09-23T17:47:22.709Z,1600883242.709 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-23T17:47:22.709Z,1600883242.709 [Startup] Stopped
2020-09-23T17:47:22.709Z,1600883242.709 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-09-23T17:47:22.710Z,1600883242.710 [Startup:A.GoToSurface] Stopped
2020-09-23T17:47:22.710Z,1600883242.710 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-23T17:47:22.710Z,1600883242.710 [Startup:StartupSatComms] Stopped
2020-09-23T17:47:22.710Z,1600883242.710 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-09-23T17:47:22.710Z,1600883242.710 [Startup:StartupSatComms:B] Stopped
2020-09-23T17:47:22.710Z,1600883242.710 [Startup:StartupSatComms:B](INFO): Requesting PLATFORM_COMMUNICATIONS with strategy: MinPower
2020-09-23T17:47:22.711Z,1600883242.711 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-23T17:47:22.711Z,1600883242.711 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-23T17:47:22.712Z,1600883242.712 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-23T17:47:22.712Z,1600883242.712 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-23T17:47:22.712Z,1600883242.712 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-23T17:47:22.712Z,1600883242.712 [BuoyancyServo](INFO): Powering down
2020-09-23T17:47:22.724Z,1600883242.724 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-23T17:47:22.724Z,1600883242.724 [ElevatorServo](INFO): Powering down
2020-09-23T17:47:22.725Z,1600883242.725 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-23T17:47:22.725Z,1600883242.725 [MassServo](INFO): Powering down
2020-09-23T17:47:22.726Z,1600883242.726 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-23T17:47:22.726Z,1600883242.726 [RudderServo](INFO): Powering down
2020-09-23T17:47:22.727Z,1600883242.727 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-23T17:47:22.727Z,1600883242.727 [ThrusterServo](INFO): Powering down
2020-09-23T17:47:22.728Z,1600883242.728 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-23T17:47:22.728Z,1600883242.728 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-23T17:47:22.728Z,1600883242.728 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-23T17:47:22.729Z,1600883242.729 [CBIT](DEBUG): Powering off loads.
2020-09-23T17:47:22.740Z,1600883242.740 [CBIT](DEBUG): Disabling WDT.
2020-09-23T17:47:22.752Z,1600883242.752 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-23T17:47:22.752Z,1600883242.752 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.855Z,1600883242.855 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.858Z,1600883242.858 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.866Z,1600883242.866 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.929Z,1600883242.929 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.933Z,1600883242.933 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:22.944Z,1600883242.944 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-23T17:47:23.012Z,1600883243.012 [logger ThreadHandler](INFO): Thread cancelled.