2020-12-02T21:32:37.612Z,1606944757.612 [Supervisor](DEBUG): Initializing supervisor. 2020-12-02T21:32:37.615Z,1606944757.615 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-12-02T21:32:37.615Z,1606944757.615 [SyncHandler](INFO): Protected caller Thread ID is 3862 2020-12-02T21:32:37.616Z,1606944757.616 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-12-02T21:32:37.616Z,1606944757.616 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-12-02T21:32:37.617Z,1606944757.617 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3863 2020-12-02T21:32:37.620Z,1606944757.620 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-12-02T21:32:37.637Z,1606944757.637 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-12-02T21:32:37.638Z,1606944757.638 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-12-02T21:32:37.638Z,1606944757.638 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3864 2020-12-02T21:32:37.639Z,1606944757.639 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-12-02T21:32:37.640Z,1606944757.640 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-12-02T21:32:37.640Z,1606944757.640 [logger ThreadHandler](INFO): Protected caller Thread ID is 3865 2020-12-02T21:32:37.643Z,1606944757.643 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-12-02T21:32:37.643Z,1606944757.643 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-12-02T21:32:37.645Z,1606944757.645 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-12-02T21:32:38.062Z,1606944758.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-12-02T21:32:38.063Z,1606944758.063 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-12-02T21:32:38.198Z,1606944758.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-12-02T21:32:38.199Z,1606944758.199 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-12-02T21:32:38.276Z,1606944758.276 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-12-02T21:32:38.461Z,1606944758.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-12-02T21:32:38.462Z,1606944758.462 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-12-02T21:32:38.557Z,1606944758.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-12-02T21:32:38.558Z,1606944758.558 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-12-02T21:32:38.650Z,1606944758.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-12-02T21:32:38.651Z,1606944758.651 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-12-02T21:32:39.026Z,1606944759.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-12-02T21:32:39.026Z,1606944759.026 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-12-02T21:32:39.129Z,1606944759.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-12-02T21:32:39.129Z,1606944759.129 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-12-02T21:32:39.617Z,1606944759.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-12-02T21:32:39.618Z,1606944759.618 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-12-02T21:32:39.872Z,1606944759.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-12-02T21:32:39.872Z,1606944759.872 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-12-02T21:32:40.165Z,1606944760.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-12-02T21:32:40.166Z,1606944760.166 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-12-02T21:32:40.373Z,1606944760.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-12-02T21:32:40.373Z,1606944760.373 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-12-02T21:32:40.510Z,1606944760.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-12-02T21:32:40.511Z,1606944760.511 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-12-02T21:32:41.175Z,1606944761.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-12-02T21:32:41.175Z,1606944761.175 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-12-02T21:32:41.494Z,1606944761.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-12-02T21:32:41.494Z,1606944761.494 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-12-02T21:32:41.692Z,1606944761.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-12-02T21:32:41.694Z,1606944761.694 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2020-12-02T21:32:41.695Z,1606944761.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2020-12-02T21:32:41.879Z,1606944761.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2020-12-02T21:32:41.959Z,1606944761.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2020-12-02T21:32:42.102Z,1606944762.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2020-12-02T21:32:42.185Z,1606944762.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2020-12-02T21:32:42.461Z,1606944762.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-12-02T21:32:42.462Z,1606944762.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2020-12-02T21:32:42.748Z,1606944762.748 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2020-12-02T21:32:42.998Z,1606944762.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2020-12-02T21:32:43.089Z,1606944763.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2020-12-02T21:32:43.291Z,1606944763.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2020-12-02T21:32:43.503Z,1606944763.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2020-12-02T21:32:43.598Z,1606944763.598 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2020-12-02T21:32:43.599Z,1606944763.599 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-12-02T21:32:43.612Z,1606944763.612 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-12-02T21:32:43.631Z,1606944763.631 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-12-02T21:32:43.631Z,1606944763.631 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-12-02T21:32:43.708Z,1606944763.708 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-12-02T21:32:43.709Z,1606944763.709 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-12-02T21:32:43.755Z,1606944763.755 [VerticalControl](DEBUG): Construct VerticalControl. 2020-12-02T21:32:43.815Z,1606944763.815 [VerticalControl] Loaded 2020-12-02T21:32:43.815Z,1606944763.815 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-12-02T21:32:43.816Z,1606944763.816 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-12-02T21:32:43.853Z,1606944763.853 [HorizontalControl] Loaded 2020-12-02T21:32:43.853Z,1606944763.853 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-12-02T21:32:43.854Z,1606944763.854 [SpeedControl](DEBUG): Construct SpeedControl. 2020-12-02T21:32:43.857Z,1606944763.857 [SpeedControl] Loaded 2020-12-02T21:32:43.857Z,1606944763.857 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-12-02T21:32:43.858Z,1606944763.858 [LoopControl](DEBUG): Construct LoopControl. 2020-12-02T21:32:43.858Z,1606944763.858 [LoopControl] Loaded 2020-12-02T21:32:43.858Z,1606944763.858 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-12-02T21:32:43.859Z,1606944763.859 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-12-02T21:32:43.859Z,1606944763.859 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-12-02T21:32:43.968Z,1606944763.968 [BuoyancyServo] Loaded 2020-12-02T21:32:43.968Z,1606944763.968 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-12-02T21:32:43.984Z,1606944763.984 [ElevatorServo] Loaded 2020-12-02T21:32:43.985Z,1606944763.985 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-12-02T21:32:44.000Z,1606944764.000 [MassServo] Loaded 2020-12-02T21:32:44.000Z,1606944764.000 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-12-02T21:32:44.015Z,1606944764.015 [RudderServo] Loaded 2020-12-02T21:32:44.016Z,1606944764.016 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-12-02T21:32:44.031Z,1606944764.031 [ThrusterServo] Loaded 2020-12-02T21:32:44.031Z,1606944764.031 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-12-02T21:32:44.031Z,1606944764.031 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-12-02T21:32:44.032Z,1606944764.032 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-12-02T21:32:44.069Z,1606944764.069 [DepthRateCalculator] Loaded 2020-12-02T21:32:44.069Z,1606944764.069 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-12-02T21:32:44.072Z,1606944764.072 [PitchRateCalculator] Loaded 2020-12-02T21:32:44.072Z,1606944764.072 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-12-02T21:32:44.080Z,1606944764.080 [SpeedCalculator] Loaded 2020-12-02T21:32:44.081Z,1606944764.081 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-12-02T21:32:44.092Z,1606944764.092 [TempGradientCalculator] Loaded 2020-12-02T21:32:44.092Z,1606944764.092 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-12-02T21:32:44.094Z,1606944764.094 [YawRateCalculator] Loaded 2020-12-02T21:32:44.095Z,1606944764.095 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-12-02T21:32:44.109Z,1606944764.109 [ElevatorOffsetCalculator] Loaded 2020-12-02T21:32:44.110Z,1606944764.110 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-12-02T21:32:44.110Z,1606944764.110 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-12-02T21:32:44.111Z,1606944764.111 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-12-02T21:32:44.189Z,1606944764.189 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-12-02T21:32:44.189Z,1606944764.189 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-12-02T21:32:44.200Z,1606944764.200 [NavChart] Loaded 2020-12-02T21:32:44.200Z,1606944764.200 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-12-02T21:32:44.204Z,1606944764.204 [UniversalFixResidualReporter] Loaded 2020-12-02T21:32:44.205Z,1606944764.205 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-12-02T21:32:44.205Z,1606944764.205 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-12-02T21:32:44.206Z,1606944764.206 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-12-02T21:32:44.498Z,1606944764.498 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-12-02T21:32:44.500Z,1606944764.500 [AHRS_M2](INFO): created writer for : platform_orientation 2020-12-02T21:32:44.502Z,1606944764.502 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-12-02T21:32:44.505Z,1606944764.505 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-12-02T21:32:44.505Z,1606944764.505 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-12-02T21:32:44.508Z,1606944764.508 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-12-02T21:32:44.509Z,1606944764.509 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-12-02T21:32:44.512Z,1606944764.512 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-12-02T21:32:44.549Z,1606944764.549 [AHRS_M2] Loaded 2020-12-02T21:32:44.550Z,1606944764.550 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-12-02T21:32:44.619Z,1606944764.619 [DataOverHttps] Loaded 2020-12-02T21:32:44.619Z,1606944764.619 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-12-02T21:32:44.620Z,1606944764.620 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408504E0 2020-12-02T21:32:44.621Z,1606944764.621 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3948 2020-12-02T21:32:44.638Z,1606944764.638 [Depth_Keller] Loaded 2020-12-02T21:32:44.638Z,1606944764.638 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-12-02T21:32:44.693Z,1606944764.693 [NAL9602] Loaded 2020-12-02T21:32:44.693Z,1606944764.693 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-12-02T21:32:44.719Z,1606944764.719 [Onboard] Loaded 2020-12-02T21:32:44.719Z,1606944764.719 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-12-02T21:32:44.720Z,1606944764.720 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408804E0 2020-12-02T21:32:44.721Z,1606944764.721 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3949 2020-12-02T21:32:44.732Z,1606944764.732 [Radio_Surface] Loaded 2020-12-02T21:32:44.732Z,1606944764.732 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-12-02T21:32:44.733Z,1606944764.733 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B04E0 2020-12-02T21:32:44.733Z,1606944764.733 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3950 2020-12-02T21:32:44.808Z,1606944764.808 [DAT] Loaded 2020-12-02T21:32:44.808Z,1606944764.808 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-12-02T21:32:45.596Z,1606944765.596 [BPC1] Loaded 2020-12-02T21:32:45.596Z,1606944765.596 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-12-02T21:32:45.596Z,1606944765.596 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-12-02T21:32:45.597Z,1606944765.597 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-12-02T21:32:45.678Z,1606944765.678 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-12-02T21:32:45.679Z,1606944765.679 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-12-02T21:32:45.775Z,1606944765.775 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-12-02T21:32:45.776Z,1606944765.776 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-12-02T21:32:45.802Z,1606944765.802 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-12-02T21:32:45.803Z,1606944765.803 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-12-02T21:32:45.998Z,1606944765.998 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-12-02T21:32:46.001Z,1606944766.001 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-12-02T21:32:46.001Z,1606944766.001 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-12-02T21:32:46.004Z,1606944766.004 [CTD_Seabird](INFO): created writer for : depth 2020-12-02T21:32:46.004Z,1606944766.004 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-12-02T21:32:46.007Z,1606944766.007 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-12-02T21:32:46.007Z,1606944766.007 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-12-02T21:32:46.010Z,1606944766.010 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-12-02T21:32:46.011Z,1606944766.011 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-12-02T21:32:46.013Z,1606944766.013 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-12-02T21:32:46.014Z,1606944766.014 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-12-02T21:32:46.017Z,1606944766.017 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-12-02T21:32:46.018Z,1606944766.018 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-12-02T21:32:46.021Z,1606944766.021 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-12-02T21:32:46.034Z,1606944766.034 [CTD_Seabird] Loaded 2020-12-02T21:32:46.034Z,1606944766.034 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-12-02T21:32:46.035Z,1606944766.035 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A584E0 2020-12-02T21:32:46.035Z,1606944766.035 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3951 2020-12-02T21:32:46.065Z,1606944766.065 [ESPComponent] Loaded 2020-12-02T21:32:46.065Z,1606944766.065 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-12-02T21:32:46.081Z,1606944766.081 [PAR_Licor] Loaded 2020-12-02T21:32:46.082Z,1606944766.082 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-12-02T21:32:46.094Z,1606944766.094 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-12-02T21:32:46.095Z,1606944766.095 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-12-02T21:32:46.097Z,1606944766.097 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-12-02T21:32:46.097Z,1606944766.097 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-12-02T21:32:46.099Z,1606944766.099 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-12-02T21:32:46.099Z,1606944766.099 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-12-02T21:32:46.101Z,1606944766.101 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-12-02T21:32:46.101Z,1606944766.101 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-12-02T21:32:46.103Z,1606944766.103 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-12-02T21:32:46.103Z,1606944766.103 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-12-02T21:32:46.105Z,1606944766.105 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-12-02T21:32:46.105Z,1606944766.105 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-12-02T21:32:46.107Z,1606944766.107 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-12-02T21:32:46.107Z,1606944766.107 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-12-02T21:32:46.109Z,1606944766.109 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.112Z,1606944766.112 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.112Z,1606944766.112 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.112Z,1606944766.112 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.114Z,1606944766.114 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.114Z,1606944766.114 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.116Z,1606944766.116 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.117Z,1606944766.117 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-12-02T21:32:46.119Z,1606944766.119 [WetLabsBB2FL] Loaded 2020-12-02T21:32:46.119Z,1606944766.119 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-12-02T21:32:46.120Z,1606944766.120 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A884E0 2020-12-02T21:32:46.120Z,1606944766.120 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3952 2020-12-02T21:32:46.121Z,1606944766.121 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-12-02T21:32:46.122Z,1606944766.122 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-12-02T21:32:46.403Z,1606944766.403 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-12-02T21:32:46.404Z,1606944766.404 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-12-02T21:32:46.534Z,1606944766.534 [SBIT](DEBUG): Construct Startup Built In Test. 2020-12-02T21:32:46.543Z,1606944766.543 [SBIT] Loaded 2020-12-02T21:32:46.543Z,1606944766.543 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-12-02T21:32:46.543Z,1606944766.543 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-12-02T21:32:46.556Z,1606944766.556 [IBIT] Loaded 2020-12-02T21:32:46.556Z,1606944766.556 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-12-02T21:32:46.559Z,1606944766.559 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-12-02T21:32:46.657Z,1606944766.657 [CBIT] Loaded 2020-12-02T21:32:46.657Z,1606944766.657 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-12-02T21:32:46.658Z,1606944766.658 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-12-02T21:32:46.662Z,1606944766.662 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-12-02T21:32:46.663Z,1606944766.663 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-12-02T21:32:46.671Z,1606944766.671 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-12-02T21:32:46.672Z,1606944766.672 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0 2020-12-02T21:32:46.673Z,1606944766.673 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3953 2020-12-02T21:32:46.677Z,1606944766.677 [Supervisor](INFO): Main Thread ID is 3861 2020-12-02T21:32:46.677Z,1606944766.677 [Supervisor](DEBUG): Running supervisor. 2020-12-02T21:32:46.678Z,1606944766.678 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3954 2020-12-02T21:32:46.680Z,1606944766.680 [controlThread ThreadHandler](INFO): Handler Thread ID is 3955 2020-12-02T21:32:46.681Z,1606944766.681 [controlThread](DEBUG): Initializing ControlThread 2020-12-02T21:32:46.681Z,1606944766.681 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-12-02T21:32:46.683Z,1606944766.683 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-12-02T21:32:46.684Z,1606944766.684 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-12-02T21:32:46.684Z,1606944766.684 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-12-02T21:32:46.685Z,1606944766.685 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-12-02T21:32:46.686Z,1606944766.686 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-12-02T21:32:46.686Z,1606944766.686 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-12-02T21:32:46.686Z,1606944766.686 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-12-02T21:32:46.687Z,1606944766.687 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-12-02T21:32:46.687Z,1606944766.687 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-12-02T21:32:46.689Z,1606944766.689 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-12-02T21:32:46.689Z,1606944766.689 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-12-02T21:32:46.697Z,1606944766.697 [SBIT](INFO): Initialize SBIT Component. 2020-12-02T21:32:46.697Z,1606944766.697 [SBIT](IMPORTANT): git: 2020-10-07_A-8-g2edcf16 2020-12-02T21:32:46.697Z,1606944766.697 [SBIT](INFO): git hash: 2edcf162c99e1cdac7c4e1eb7e8495acc35f264a 2020-12-02T21:32:46.698Z,1606944766.698 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-12-02T21:32:46.699Z,1606944766.699 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020 2020-12-02T21:32:46.700Z,1606944766.700 [SBIT](INFO): Beginning SBIT in 34.000000 seconds. 2020-12-02T21:32:46.701Z,1606944766.701 [IBIT](INFO): Initialize IBIT Component. 2020-12-02T21:32:46.701Z,1606944766.701 [CBIT](DEBUG): Initialize CBIT Component. 2020-12-02T21:32:46.702Z,1606944766.702 [logger ThreadHandler](INFO): Handler Thread ID is 3956 2020-12-02T21:32:46.713Z,1606944766.713 [CBIT](DEBUG): Initialized mux pins. 2020-12-02T21:32:46.713Z,1606944766.713 [CBIT](DEBUG): Initializing the watchdog timer. 2020-12-02T21:32:46.721Z,1606944766.721 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3957 2020-12-02T21:32:46.722Z,1606944766.722 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-12-02T21:32:46.733Z,1606944766.733 [Onboard ThreadHandler](INFO): Handler Thread ID is 3958 2020-12-02T21:32:46.737Z,1606944766.737 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-12-02T21:32:46.737Z,1606944766.737 [CBIT](DEBUG): Initializing heartbeat. 2020-12-02T21:32:46.751Z,1606944766.751 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3959 2020-12-02T21:32:46.765Z,1606944766.765 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3960 2020-12-02T21:32:46.766Z,1606944766.766 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-12-02T21:32:46.770Z,1606944766.770 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3962 2020-12-02T21:32:46.771Z,1606944766.771 [WetLabsBB2FL](INFO): Powering down 2020-12-02T21:32:46.793Z,1606944766.793 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3963 2020-12-02T21:32:46.797Z,1606944766.797 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-12-02T21:32:46.797Z,1606944766.797 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-12-02T21:32:46.797Z,1606944766.797 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-12-02T21:32:46.797Z,1606944766.797 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-12-02T21:32:46.797Z,1606944766.797 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-12-02T21:32:46.797Z,1606944766.797 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-12-02T21:32:46.798Z,1606944766.798 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-12-02T21:32:46.798Z,1606944766.798 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-12-02T21:32:46.798Z,1606944766.798 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-12-02T21:32:46.798Z,1606944766.798 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-12-02T21:32:46.798Z,1606944766.798 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-12-02T21:32:46.798Z,1606944766.798 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-12-02T21:32:46.799Z,1606944766.799 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-12-02T21:32:46.799Z,1606944766.799 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-12-02T21:32:46.799Z,1606944766.799 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-12-02T21:32:46.799Z,1606944766.799 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-12-02T21:32:46.813Z,1606944766.813 [CBIT](DEBUG): Deactivating GF circuits. 2020-12-02T21:32:46.813Z,1606944766.813 [CBIT](DEBUG): Deactivating emergency mode. 2020-12-02T21:32:46.849Z,1606944766.849 [CBIT](DEBUG): Backplane powered. 2020-12-02T21:32:46.850Z,1606944766.850 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-12-02T21:32:46.860Z,1606944766.860 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-12-02T21:32:46.870Z,1606944766.870 [MissionManager](DEBUG): 2020-12-02T21:32:46.871Z,1606944766.871 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-12-02T21:32:46.947Z,1606944766.947 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-12-02T21:32:46.948Z,1606944766.948 [Default:A.Wait](DEBUG): Construct Wait. 2020-12-02T21:32:46.966Z,1606944766.966 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-12-02T21:32:47.010Z,1606944767.010 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-12-02T21:32:47.013Z,1606944767.013 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-12-02T21:32:47.019Z,1606944767.019 [Default:E.Execute](DEBUG): Construct Execute. 2020-12-02T21:32:47.050Z,1606944767.050 [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-12-02T21:32:47.055Z,1606944767.055 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,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-12-02T21:32:47.069Z,1606944767.069 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-12-02T21:32:47.102Z,1606944767.102 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2020-12-02T21:32:47.105Z,1606944767.105 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-12-02T21:32:47.105Z,1606944767.105 [DAT](INFO): Powering up 2020-12-02T21:32:47.105Z,1606944767.105 [DAT](DEBUG): Initializing DAT. 2020-12-02T21:32:47.165Z,1606944767.165 [Radio_Surface](INFO): Powering up 2020-12-02T21:32:47.177Z,1606944767.177 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-12-02T21:32:47.225Z,1606944767.225 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-12-02T21:32:47.285Z,1606944767.285 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-12-02T21:32:47.295Z,1606944767.295 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-12-02T21:32:47.296Z,1606944767.296 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-12-02T21:32:47.313Z,1606944767.313 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-12-02T21:32:47.314Z,1606944767.314 [MassServo](DEBUG): Initializing EZServoServo. 2020-12-02T21:32:47.321Z,1606944767.321 [MassServo](DEBUG): Initializing MassServo. 2020-12-02T21:32:47.322Z,1606944767.322 [RudderServo](DEBUG): Initializing EZServoServo. 2020-12-02T21:32:47.329Z,1606944767.329 [RudderServo](DEBUG): Initializing RudderServo. 2020-12-02T21:32:47.330Z,1606944767.330 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-12-02T21:32:47.337Z,1606944767.337 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-12-02T21:32:48.253Z,1606944768.253 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-12-02T21:32:48.253Z,1606944768.253 [RudderServo](FAULT): Rudder failed to initialize 2020-12-02T21:32:48.253Z,1606944768.253 [RudderServo] Communications Fault, FailCount= 1 2020-12-02T21:32:48.253Z,1606944768.253 [RudderServo](ERROR): Communications Fault 2020-12-02T21:32:48.364Z,1606944768.364 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-12-02T21:32:48.522Z,1606944768.522 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-12-02T21:32:48.522Z,1606944768.522 [RudderServo](INFO): Powering down 2020-12-02T21:32:49.224Z,1606944769.224 [RudderServo](DEBUG): Initializing EZServoServo. 2020-12-02T21:32:49.342Z,1606944769.342 [RudderServo](DEBUG): Initializing RudderServo. 2020-12-02T21:32:49.346Z,1606944769.346 [CBIT](INFO): Clearing failed state for component RudderServo 2020-12-02T21:32:49.346Z,1606944769.346 [RudderServo] No Fault, FailCount= 1 2020-12-02T21:32:53.801Z,1606944773.801 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2020-12-02T21:32:59.227Z,1606944779.227 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004903 2020-12-02T21:33:15.038Z,1606944795.038 [NAL9602](INFO): Powering up NAL9602 2020-12-02T21:33:21.135Z,1606944801.135 [SBIT](IMPORTANT): Beginning Startup BIT 2020-12-02T21:33:21.143Z,1606944801.143 [CBIT](IMPORTANT): Beginning ground fault scan 2020-12-02T21:33:25.983Z,1606944805.983 [NAL9602](INFO): NAL9602 initialized 2020-12-02T21:33:32.202Z,1606944812.202 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.025007 CHAN A1 (24V): -0.007530 CHAN A2 (12V): -0.004074 CHAN A3 (5V): -0.003089 CHAN B0 (3.3V): -0.001547 CHAN B1 (3.15aV): -0.001081 CHAN B2 (3.15bV): -0.001684 CHAN B3 (GND): -0.000480 OPEN: 0.004467 Full Scale Calc: 4.765 mA, -1.589 mA 2020-12-02T21:33:47.455Z,1606944827.455 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2020-12-02T21:33:47.455Z,1606944827.455 [DAT] Communications Fault, FailCount= 1 2020-12-02T21:33:47.455Z,1606944827.455 [DAT](ERROR): Communications Fault 2020-12-02T21:33:47.525Z,1606944827.525 [CBIT](ERROR): Communications Fault in component: DAT 2020-12-02T21:33:47.855Z,1606944827.855 [DAT](INFO): Powering down 2020-12-02T21:33:48.727Z,1606944828.727 [CBIT](INFO): Clearing failed state for component DAT 2020-12-02T21:33:48.727Z,1606944828.727 [DAT] No Fault, FailCount= 1 2020-12-02T21:33:51.103Z,1606944831.103 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-12-02T21:33:51.103Z,1606944831.103 [DAT](INFO): Powering up 2020-12-02T21:33:51.103Z,1606944831.103 [DAT](DEBUG): Initializing DAT. 2020-12-02T21:33:51.153Z,1606944831.153 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-12-02T21:34:01.609Z,1606944841.609 [DAT](INFO): DAT read: 2020-12-02T21:34:01.611Z,1606944841.611 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2020-12-02T21:34:02.803Z,1606944842.803 [DAT](INFO): DAT read: MF Frequency Band 2020-12-02T21:34:02.804Z,1606944842.804 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.12.21 2020-12-02T21:34:02.805Z,1606944842.805 [DAT](INFO): DAT read: Dec 2 2020 21:32:13 2020-12-02T21:34:03.615Z,1606944843.615 [DAT](INFO): DAT read: Features enabled [Bearing] 2020-12-02T21:34:03.617Z,1606944843.617 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-12-02T21:34:03.617Z,1606944843.617 [DAT](INFO): commRate: 800 2020-12-02T21:34:05.646Z,1606944845.646 [DAT](INFO): entering command mode 2020-12-02T21:34:06.049Z,1606944846.049 [DAT](INFO): DAT read: 2020-12-02T21:34:06.050Z,1606944846.050 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:06.457Z,1606944846.457 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:06.869Z,1606944846.869 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:07.259Z,1606944847.259 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:07.654Z,1606944847.654 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:08.063Z,1606944848.063 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:08.467Z,1606944848.467 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:08.866Z,1606944848.866 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:09.271Z,1606944849.271 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:09.671Z,1606944849.671 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:10.079Z,1606944850.079 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:10.487Z,1606944850.487 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:10.897Z,1606944850.897 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:11.301Z,1606944851.301 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:11.709Z,1606944851.709 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:12.107Z,1606944852.107 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:12.502Z,1606944852.502 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:12.903Z,1606944852.903 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:13.314Z,1606944853.314 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:13.710Z,1606944853.710 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:14.119Z,1606944854.119 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:14.527Z,1606944854.527 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:14.930Z,1606944854.930 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:14.943Z,1606944854.943 [SBIT](IMPORTANT): SBIT PASSED 2020-12-02T21:34:14.943Z,1606944854.943 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-12-02T21:34:14.944Z,1606944854.944 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2020-12-02T21:34:14.944Z,1606944854.944 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2020-12-02T21:34:14.946Z,1606944854.946 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2020-12-02T21:34:14.946Z,1606944854.946 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-12-02T21:34:14.946Z,1606944854.946 [SBIT](IMPORTANT): DAT.verbosity=3 count; 2020-12-02T21:34:14.948Z,1606944854.948 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2020-12-02T21:34:14.948Z,1606944854.948 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-12-02T21:34:14.948Z,1606944854.948 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2020-12-02T21:34:14.949Z,1606944854.949 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter; 2020-12-02T21:34:14.949Z,1606944854.949 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2020-12-02T21:34:14.949Z,1606944854.949 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2020-12-02T21:34:14.949Z,1606944854.949 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2020-12-02T21:34:14.949Z,1606944854.949 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter; 2020-12-02T21:34:14.949Z,1606944854.949 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter; 2020-12-02T21:34:15.341Z,1606944855.341 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:15.355Z,1606944855.355 [MissionManager](IMPORTANT): Started mission Startup 2020-12-02T21:34:15.355Z,1606944855.355 [Startup] Running Loop=1 2020-12-02T21:34:15.356Z,1606944855.356 [Startup](DEBUG): Aggregate::initialize Startup 2020-12-02T21:34:15.356Z,1606944855.356 [Startup:A.GoToSurface] Running Loop=1 2020-12-02T21:34:15.356Z,1606944855.356 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-12-02T21:34:15.356Z,1606944855.356 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-12-02T21:34:15.357Z,1606944855.357 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-12-02T21:34:15.357Z,1606944855.357 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-12-02T21:34:15.357Z,1606944855.357 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-12-02T21:34:15.358Z,1606944855.358 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-12-02T21:34:15.359Z,1606944855.359 [Startup:StartupSatComms] Running Loop=1 2020-12-02T21:34:15.359Z,1606944855.359 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-12-02T21:34:15.359Z,1606944855.359 [Startup:StartupSatComms:A] Running Loop=1 2020-12-02T21:34:15.735Z,1606944855.735 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:15.754Z,1606944855.754 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-12-02T21:34:16.145Z,1606944856.145 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:16.553Z,1606944856.553 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:16.965Z,1606944856.965 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:17.363Z,1606944857.363 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:17.755Z,1606944857.755 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:18.159Z,1606944858.159 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:18.563Z,1606944858.563 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:18.967Z,1606944858.967 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:19.371Z,1606944859.371 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:19.774Z,1606944859.774 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:20.183Z,1606944860.183 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:20.579Z,1606944860.579 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:20.986Z,1606944860.986 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-02T21:34:20.987Z,1606944860.987 [DAT](FAULT): failed to enter command mode 2020-12-02T21:34:21.406Z,1606944861.406 [DAT](INFO): entering command mode 2020-12-02T21:34:21.805Z,1606944861.805 [DAT](INFO): DAT read: user:1> 2020-12-02T21:34:21.806Z,1606944861.806 [DAT](INFO): DAT read: Command '+++' not found 2020-12-02T21:34:21.807Z,1606944861.807 [DAT](INFO): DAT read: Error 2020-12-02T21:34:21.807Z,1606944861.807 [DAT](INFO): setting verbose to 3 2020-12-02T21:34:22.211Z,1606944862.211 [DAT](INFO): DAT read: user:2> 2020-12-02T21:34:22.212Z,1606944862.212 [DAT](INFO): DAT read: Verbose | 3 2020-12-02T21:34:22.213Z,1606944862.213 [DAT](INFO): set verbose to 3 2020-12-02T21:34:22.213Z,1606944862.213 [DAT](INFO): setting DatVerbose to 27440 2020-12-02T21:34:22.613Z,1606944862.613 [DAT](INFO): DAT read: user:3> 2020-12-02T21:34:22.614Z,1606944862.614 [DAT](INFO): DAT read: DatVerbose | 27440 2020-12-02T21:34:22.615Z,1606944862.615 [DAT](INFO): set DatVerbose to 27440 2020-12-02T21:34:22.615Z,1606944862.615 [DAT](INFO): setting transmit power to 8 2020-12-02T21:34:23.007Z,1606944863.007 [DAT](INFO): DAT read: user:4> 2020-12-02T21:34:23.008Z,1606944863.008 [DAT](INFO): DAT read: TxPower | 8 (Max) 2020-12-02T21:34:23.008Z,1606944863.008 [DAT](INFO): set transmit power to 8 2020-12-02T21:34:23.008Z,1606944863.008 [DAT](INFO): setting local address to 5 2020-12-02T21:34:23.407Z,1606944863.407 [DAT](INFO): DAT read: user:5> 2020-12-02T21:34:23.408Z,1606944863.408 [DAT](INFO): DAT read: LocalAddr | 5 2020-12-02T21:34:23.408Z,1606944863.408 [DAT](INFO): set local address to 5 2020-12-02T21:35:15.535Z,1606944915.535 [Startup:StartupSatComms:A](INFO): Timed out from 2020-12-02T21:34:15.4Z 2020-12-02T21:35:15.535Z,1606944915.535 [Startup:StartupSatComms:A] Stopped 2020-12-02T21:35:15.535Z,1606944915.535 [Startup:StartupSatComms:B] Running Loop=1 2020-12-02T21:35:15.959Z,1606944915.959 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-12-02T21:35:24.426Z,1606944924.426 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201120T173928/Courier0004.lzma 2020-12-02T21:35:25.429Z,1606944925.429 [DataOverHttps](INFO): Moved sent file to Logs/20201120T173928/Courier0004.lzma.bak 2020-12-02T21:35:25.429Z,1606944925.429 [DataOverHttps](INFO): SBD MOMSN=13381357 2020-12-02T21:35:42.838Z,1606944942.838 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201120T174640/Courier0000.lzma 2020-12-02T21:35:43.838Z,1606944943.838 [DataOverHttps](INFO): Moved sent file to Logs/20201120T174640/Courier0000.lzma.bak 2020-12-02T21:35:43.838Z,1606944943.838 [DataOverHttps](INFO): SBD MOMSN=13381359 2020-12-02T21:35:47.042Z,1606944947.042 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-12-02T21:35:47.042Z,1606944947.042 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:35:47.052Z,1606944947.052 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:35:47.470Z,1606944947.470 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:35:47.470Z,1606944947.470 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-12-02T21:36:01.337Z,1606944961.337 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201120T174640/Courier0006.lzma 2020-12-02T21:36:02.339Z,1606944962.339 [DataOverHttps](INFO): Moved sent file to Logs/20201120T174640/Courier0006.lzma.bak 2020-12-02T21:36:02.339Z,1606944962.339 [DataOverHttps](INFO): SBD MOMSN=13381362 2020-12-02T21:36:15.743Z,1606944975.743 [Startup:StartupSatComms:B](INFO): Timed out from 2020-12-02T21:35:15.5Z 2020-12-02T21:36:15.743Z,1606944975.743 [Startup:StartupSatComms:B] Stopped 2020-12-02T21:36:15.743Z,1606944975.743 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-12-02T21:36:15.743Z,1606944975.743 [Startup:StartupSatComms] Stopped 2020-12-02T21:36:15.743Z,1606944975.743 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-12-02T21:36:15.744Z,1606944975.744 [Startup](INFO): Completed Startup 2020-12-02T21:36:15.744Z,1606944975.744 [MissionManager](INFO): Startup is completed. 2020-12-02T21:36:15.749Z,1606944975.749 [MissionManager](INFO): Uninitializing Mission Startup 2020-12-02T21:36:15.749Z,1606944975.749 [Startup] Stopped 2020-12-02T21:36:15.749Z,1606944975.749 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-12-02T21:36:15.749Z,1606944975.749 [Startup:A.GoToSurface] Stopped 2020-12-02T21:36:15.749Z,1606944975.749 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-12-02T21:36:16.139Z,1606944976.139 [MissionManager](IMPORTANT): Started mission Default 2020-12-02T21:36:16.140Z,1606944976.140 [Default] Running Loop=1 2020-12-02T21:36:16.140Z,1606944976.140 [Default](DEBUG): Aggregate::initialize Default 2020-12-02T21:36:16.140Z,1606944976.140 [Default:B.GoToSurface] Running Loop=1 2020-12-02T21:36:16.140Z,1606944976.140 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-12-02T21:36:16.140Z,1606944976.140 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-12-02T21:36:16.145Z,1606944976.145 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-12-02T21:36:16.145Z,1606944976.145 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-12-02T21:36:16.145Z,1606944976.145 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-12-02T21:36:16.146Z,1606944976.146 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-12-02T21:36:16.146Z,1606944976.146 [Default:A.Wait] Running Loop=1 2020-12-02T21:36:16.146Z,1606944976.146 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-12-02T21:36:19.712Z,1606944979.712 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20201202T213237/Courier0000.lzma 2020-12-02T21:36:20.714Z,1606944980.714 [DataOverHttps](INFO): Moved sent file to Logs/20201202T213237/Courier0000.lzma.bak 2020-12-02T21:36:20.714Z,1606944980.714 [DataOverHttps](INFO): SBD MOMSN=13381364 2020-12-02T21:36:29.475Z,1606944989.475 [Default:A.Wait](INFO): Done Waiting. 2020-12-02T21:36:29.475Z,1606944989.475 [Default:A.Wait] Stopped 2020-12-02T21:36:29.475Z,1606944989.475 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-12-02T21:36:29.883Z,1606944989.883 [Default:CheckIn] Running Loop=1 2020-12-02T21:36:29.883Z,1606944989.883 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-12-02T21:36:29.883Z,1606944989.883 [Default:CheckIn:Read_GPS] Running Loop=1 2020-12-02T21:36:30.271Z,1606944990.271 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-12-02T21:38:29.855Z,1606945109.855 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-12-02T21:38:48.066Z,1606945128.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-12-02T21:38:48.066Z,1606945128.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:38:48.084Z,1606945128.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:38:48.547Z,1606945128.547 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:38:48.547Z,1606945128.547 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-12-02T21:41:30.043Z,1606945290.043 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-02T21:36:29.9Z 2020-12-02T21:41:30.043Z,1606945290.043 [Default:CheckIn:Read_GPS] Stopped 2020-12-02T21:41:30.043Z,1606945290.043 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-12-02T21:41:30.446Z,1606945290.446 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-12-02T21:41:39.368Z,1606945299.368 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201202T213237/Courier0004.lzma 2020-12-02T21:41:40.370Z,1606945300.370 [DataOverHttps](INFO): Moved sent file to Logs/20201202T213237/Courier0004.lzma.bak 2020-12-02T21:41:40.371Z,1606945300.371 [DataOverHttps](INFO): SBD MOMSN=13381368 2020-12-02T21:41:49.061Z,1606945309.061 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-12-02T21:41:49.061Z,1606945309.061 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:41:49.103Z,1606945309.103 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:41:49.482Z,1606945309.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:41:49.482Z,1606945309.482 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-12-02T21:42:02.012Z,1606945322.012 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20201119T203733/Express0011.lzma 2020-12-02T21:42:03.015Z,1606945323.015 [DataOverHttps](INFO): Moved sent file to Logs/20201119T203733/Express0011.lzma.bak 2020-12-02T21:42:03.015Z,1606945323.015 [DataOverHttps](INFO): SBD MOMSN=13381370 2020-12-02T21:43:29.078Z,1606945409.078 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-12-02T21:43:29.078Z,1606945409.078 [NAL9602] Data Fault, FailCount= 1 2020-12-02T21:43:29.078Z,1606945409.078 [NAL9602](ERROR): Data Fault 2020-12-02T21:43:29.117Z,1606945409.117 [CBIT](ERROR): Data Fault in component: NAL9602 2020-12-02T21:43:29.490Z,1606945409.490 [NAL9602](INFO): Powering down 2020-12-02T21:43:30.307Z,1606945410.307 [CBIT](INFO): Clearing failed state for component NAL9602 2020-12-02T21:43:30.307Z,1606945410.307 [NAL9602] No Fault, FailCount= 1 2020-12-02T21:43:59.794Z,1606945439.794 [NAL9602](INFO): Powering up NAL9602 2020-12-02T21:44:10.699Z,1606945450.699 [NAL9602](INFO): NAL9602 initialized 2020-12-02T21:44:49.895Z,1606945489.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-12-02T21:44:49.895Z,1606945489.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:44:49.905Z,1606945489.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:44:50.313Z,1606945490.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:44:50.313Z,1606945490.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-12-02T21:44:55.665Z,1606945495.665 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-12-02T21:44:58.916Z,1606945498.916 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20201119T203733/Express0014.lzma 2020-12-02T21:44:59.918Z,1606945499.918 [DataOverHttps](INFO): Moved sent file to Logs/20201119T203733/Express0014.lzma.bak 2020-12-02T21:44:59.919Z,1606945499.919 [DataOverHttps](INFO): SBD MOMSN=13381387 2020-12-02T21:47:50.954Z,1606945670.954 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-12-02T21:47:50.954Z,1606945670.954 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:47:50.964Z,1606945670.964 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:47:51.354Z,1606945671.354 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:47:51.354Z,1606945671.354 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-12-02T21:47:55.486Z,1606945675.486 [DataOverHttps](INFO): Sending 1150 bytes from file Logs/20201120T012937/Express0001.lzma 2020-12-02T21:47:56.486Z,1606945676.486 [DataOverHttps](INFO): Moved sent file to Logs/20201120T012937/Express0001.lzma.bak 2020-12-02T21:47:56.486Z,1606945676.486 [DataOverHttps](INFO): SBD MOMSN=13381436 2020-12-02T21:48:17.202Z,1606945697.202 [DataOverHttps](INFO): Sending 1000 bytes from file Logs/20201120T012937/Express0005.lzma 2020-12-02T21:48:18.202Z,1606945698.202 [DataOverHttps](INFO): Moved sent file to Logs/20201120T012937/Express0005.lzma.bak 2020-12-02T21:48:18.202Z,1606945698.202 [DataOverHttps](INFO): SBD MOMSN=13381467 2020-12-02T21:48:37.435Z,1606945717.435 [DataOverHttps](INFO): Sending 1151 bytes from file Logs/20201120T173928/Express0001.lzma 2020-12-02T21:48:38.434Z,1606945718.434 [DataOverHttps](INFO): Moved sent file to Logs/20201120T173928/Express0001.lzma.bak 2020-12-02T21:48:38.434Z,1606945718.434 [DataOverHttps](INFO): SBD MOMSN=13381508 2020-12-02T21:48:59.155Z,1606945739.155 [DataOverHttps](INFO): Sending 955 bytes from file Logs/20201120T173928/Express0005.lzma 2020-12-02T21:49:00.147Z,1606945740.147 [DataOverHttps](INFO): Moved sent file to Logs/20201120T173928/Express0005.lzma.bak 2020-12-02T21:49:00.147Z,1606945740.147 [DataOverHttps](INFO): SBD MOMSN=13381539 2020-12-02T21:49:19.414Z,1606945759.414 [DataOverHttps](INFO): Sending 655 bytes from file Logs/20201120T174640/Express0001.lzma 2020-12-02T21:49:20.414Z,1606945760.414 [DataOverHttps](INFO): Moved sent file to Logs/20201120T174640/Express0001.lzma.bak 2020-12-02T21:49:20.415Z,1606945760.415 [DataOverHttps](INFO): SBD MOMSN=13381575 2020-12-02T21:49:39.694Z,1606945779.694 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20201120T174640/Express0004.lzma 2020-12-02T21:49:40.694Z,1606945780.694 [DataOverHttps](INFO): Moved sent file to Logs/20201120T174640/Express0004.lzma.bak 2020-12-02T21:49:40.695Z,1606945780.695 [DataOverHttps](INFO): SBD MOMSN=13381590 2020-12-02T21:49:59.922Z,1606945799.922 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20201120T174640/Express0007.lzma 2020-12-02T21:50:00.922Z,1606945800.922 [DataOverHttps](INFO): Moved sent file to Logs/20201120T174640/Express0007.lzma.bak 2020-12-02T21:50:00.923Z,1606945800.923 [DataOverHttps](INFO): SBD MOMSN=13381592 2020-12-02T21:50:21.614Z,1606945821.614 [DataOverHttps](INFO): Sending 1084 bytes from file Logs/20201202T213237/Express0001.lzma 2020-12-02T21:50:22.615Z,1606945822.615 [DataOverHttps](INFO): Moved sent file to Logs/20201202T213237/Express0001.lzma.bak 2020-12-02T21:50:22.615Z,1606945822.615 [DataOverHttps](INFO): SBD MOMSN=13381596 2020-12-02T21:50:42.150Z,1606945842.150 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20201202T213237/Express0005.lzma 2020-12-02T21:50:43.150Z,1606945843.150 [DataOverHttps](INFO): Moved sent file to Logs/20201202T213237/Express0005.lzma.bak 2020-12-02T21:50:43.151Z,1606945843.151 [DataOverHttps](INFO): SBD MOMSN=13381626 2020-12-02T21:50:48.363Z,1606945848.363 [Default:CheckIn:Read_Iridium] Stopped 2020-12-02T21:50:48.363Z,1606945848.363 [Default:CheckIn:C.Wait] Running Loop=1 2020-12-02T21:50:48.363Z,1606945848.363 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-12-02T21:50:51.906Z,1606945851.906 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-12-02T21:50:51.906Z,1606945851.906 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:50:51.920Z,1606945851.920 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:50:52.303Z,1606945852.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:50:52.303Z,1606945852.303 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-12-02T21:53:52.877Z,1606946032.877 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2020-12-02T21:53:52.877Z,1606946032.878 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:53:52.887Z,1606946032.887 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:53:53.354Z,1606946033.354 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:53:53.354Z,1606946033.354 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2020-12-02T21:54:00.573Z,1606946040.573 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2020-12-02T21:54:13.878Z,1606946053.878 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-12-02T21:54:13.878Z,1606946053.878 [NAL9602] Data Fault, FailCount= 2 2020-12-02T21:54:13.878Z,1606946053.878 [NAL9602](ERROR): Data Fault 2020-12-02T21:54:13.981Z,1606946053.981 [CBIT](ERROR): Data Fault in component: NAL9602 2020-12-02T21:54:14.286Z,1606946054.286 [NAL9602](INFO): Powering down 2020-12-02T21:54:15.121Z,1606946055.121 [CBIT](INFO): Clearing failed state for component NAL9602 2020-12-02T21:54:15.121Z,1606946055.121 [NAL9602] No Fault, FailCount= 2 2020-12-02T21:54:44.587Z,1606946084.587 [NAL9602](INFO): Powering up NAL9602 2020-12-02T21:54:55.490Z,1606946095.490 [NAL9602](INFO): NAL9602 initialized 2020-12-02T21:55:48.831Z,1606946148.831 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-12-02T21:55:48.831Z,1606946148.831 [Default:CheckIn:C.Wait] Stopped 2020-12-02T21:55:48.831Z,1606946148.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-12-02T21:55:48.831Z,1606946148.831 [Default:CheckIn:D] Running Loop=1 2020-12-02T21:55:49.236Z,1606946149.236 [Default:CheckIn:D] Stopped 2020-12-02T21:55:49.237Z,1606946149.237 [Default:CheckIn:E] Running Loop=1 2020-12-02T21:55:49.659Z,1606946149.659 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.551609 min 2020-12-02T21:55:49.659Z,1606946149.659 [Default:CheckIn:E] Stopped 2020-12-02T21:55:49.659Z,1606946149.659 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-12-02T21:55:49.659Z,1606946149.659 [Default:CheckIn] Stopped 2020-12-02T21:55:49.659Z,1606946149.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-12-02T21:55:49.660Z,1606946149.660 [Default:CheckIn](INFO): Running loop #2 2020-12-02T21:55:49.660Z,1606946149.660 [Default:CheckIn] Running Loop=2 2020-12-02T21:55:49.660Z,1606946149.660 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-12-02T21:55:49.660Z,1606946149.660 [Default:CheckIn:Read_GPS] Running Loop=1 2020-12-02T21:56:53.894Z,1606946213.894 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2020-12-02T21:56:53.894Z,1606946213.894 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:56:53.904Z,1606946213.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:56:54.357Z,1606946214.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:56:54.357Z,1606946214.357 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2020-12-02T21:59:54.886Z,1606946394.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2020-12-02T21:59:54.886Z,1606946394.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T21:59:54.896Z,1606946394.896 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T21:59:55.294Z,1606946395.294 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T21:59:55.294Z,1606946395.294 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2020-12-02T22:00:49.838Z,1606946449.838 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-02T21:55:49.7Z 2020-12-02T22:00:49.838Z,1606946449.838 [Default:CheckIn:Read_GPS] Stopped 2020-12-02T22:00:49.838Z,1606946449.838 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-12-02T22:01:07.044Z,1606946467.044 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201202T213237/Courier0007.lzma 2020-12-02T22:01:08.046Z,1606946468.046 [DataOverHttps](INFO): Moved sent file to Logs/20201202T213237/Courier0007.lzma.bak 2020-12-02T22:01:08.047Z,1606946468.047 [DataOverHttps](INFO): SBD MOMSN=13381677 2020-12-02T22:01:27.352Z,1606946487.352 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20201202T213237/Express0008.lzma 2020-12-02T22:01:28.354Z,1606946488.354 [DataOverHttps](INFO): Moved sent file to Logs/20201202T213237/Express0008.lzma.bak 2020-12-02T22:01:28.355Z,1606946488.355 [DataOverHttps](INFO): SBD MOMSN=13381679 2020-12-02T22:01:33.464Z,1606946493.464 [Default:CheckIn:Read_Iridium] Stopped 2020-12-02T22:01:33.464Z,1606946493.464 [Default:CheckIn:C.Wait] Running Loop=1 2020-12-02T22:01:33.464Z,1606946493.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-12-02T22:02:55.890Z,1606946575.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2020-12-02T22:02:55.890Z,1606946575.890 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T22:02:55.900Z,1606946575.900 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T22:02:56.311Z,1606946576.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T22:02:56.311Z,1606946576.311 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2020-12-02T22:03:47.606Z,1606946627.606 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5. 2020-12-02T22:03:47.608Z,1606946627.608 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2020-12-02T22:04:56.330Z,1606946696.330 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-12-02T22:04:56.331Z,1606946696.331 [NAL9602] Data Fault, FailCount= 3 2020-12-02T22:04:56.331Z,1606946696.331 [NAL9602](ERROR): Data Fault 2020-12-02T22:04:56.431Z,1606946696.431 [CBIT](ERROR): Data Fault in component: NAL9602 2020-12-02T22:04:56.730Z,1606946696.730 [NAL9602](INFO): Powering down 2020-12-02T22:04:57.601Z,1606946697.601 [CBIT](INFO): Clearing failed state for component NAL9602 2020-12-02T22:04:57.601Z,1606946697.601 [NAL9602] No Fault, FailCount= 3 2020-12-02T22:05:27.030Z,1606946727.030 [NAL9602](INFO): Powering up NAL9602 2020-12-02T22:05:37.957Z,1606946737.957 [NAL9602](INFO): NAL9602 initialized 2020-12-02T22:05:56.965Z,1606946756.965 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2020-12-02T22:05:56.965Z,1606946756.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T22:05:56.986Z,1606946756.986 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T22:05:57.386Z,1606946757.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T22:05:57.386Z,1606946757.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2020-12-02T22:06:34.111Z,1606946794.111 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-12-02T22:06:34.111Z,1606946794.111 [Default:CheckIn:C.Wait] Stopped 2020-12-02T22:06:34.111Z,1606946794.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-12-02T22:06:34.111Z,1606946794.111 [Default:CheckIn:D] Running Loop=1 2020-12-02T22:06:34.514Z,1606946794.514 [Default:CheckIn:D] Stopped 2020-12-02T22:06:34.514Z,1606946794.514 [Default:CheckIn:E] Running Loop=1 2020-12-02T22:06:34.935Z,1606946794.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.306236 min 2020-12-02T22:06:34.935Z,1606946794.935 [Default:CheckIn:E] Stopped 2020-12-02T22:06:34.935Z,1606946794.935 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-12-02T22:06:34.935Z,1606946794.935 [Default:CheckIn] Stopped 2020-12-02T22:06:34.935Z,1606946794.935 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-12-02T22:06:34.936Z,1606946794.936 [Default:CheckIn](INFO): Running loop #3 2020-12-02T22:06:34.936Z,1606946794.936 [Default:CheckIn] Running Loop=3 2020-12-02T22:06:34.936Z,1606946794.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-12-02T22:06:34.936Z,1606946794.936 [Default:CheckIn:Read_GPS] Running Loop=1 2020-12-02T22:08:57.930Z,1606946937.930 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2020-12-02T22:08:57.930Z,1606946937.930 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T22:08:57.963Z,1606946937.963 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T22:08:58.340Z,1606946938.340 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T22:08:58.340Z,1606946938.340 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2020-12-02T22:11:35.095Z,1606947095.095 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-02T22:06:34.9Z 2020-12-02T22:11:35.095Z,1606947095.095 [Default:CheckIn:Read_GPS] Stopped 2020-12-02T22:11:35.096Z,1606947095.096 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-12-02T22:11:43.697Z,1606947103.697 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20201202T213237/Courier0010.lzma 2020-12-02T22:11:44.698Z,1606947104.698 [DataOverHttps](INFO): Moved sent file to Logs/20201202T213237/Courier0010.lzma.bak 2020-12-02T22:11:44.699Z,1606947104.699 [DataOverHttps](INFO): SBD MOMSN=13381695 2020-12-02T22:11:59.006Z,1606947119.006 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2020-12-02T22:11:59.006Z,1606947119.006 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-02T22:11:59.016Z,1606947119.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-02T22:11:59.410Z,1606947119.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-02T22:11:59.410Z,1606947119.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2020-12-02T22:12:45.329Z,1606947165.329 [CommandLine](IMPORTANT): got command restart application 2020-12-02T22:12:46.337Z,1606947166.337 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2020-12-02T22:12:46.337Z,1606947166.337 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:46.337Z,1606947166.337 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.513Z,1606947166.513 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-12-02T22:12:46.513Z,1606947166.513 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.514Z,1606947166.514 [CommandLine](INFO): Join timeout helper Thread ID is 4044 2020-12-02T22:12:46.521Z,1606947166.521 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-12-02T22:12:46.521Z,1606947166.521 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.521Z,1606947166.521 [NavChartDb](INFO): Join timeout helper Thread ID is 4045 2020-12-02T22:12:46.614Z,1606947166.614 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:46.614Z,1606947166.614 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.629Z,1606947166.629 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-12-02T22:12:46.629Z,1606947166.629 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.629Z,1606947166.629 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4046 2020-12-02T22:12:46.697Z,1606947166.697 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:46.697Z,1606947166.697 [WetLabsBB2FL](INFO): Powering down 2020-12-02T22:12:46.698Z,1606947166.698 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.699Z,1606947166.699 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-12-02T22:12:46.699Z,1606947166.699 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.699Z,1606947166.699 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4047 2020-12-02T22:12:46.865Z,1606947166.865 [CTD_Seabird](INFO): Powering down 2020-12-02T22:12:46.877Z,1606947166.877 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:46.877Z,1606947166.877 [CTD_Seabird](INFO): Powering down 2020-12-02T22:12:46.889Z,1606947166.889 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.909Z,1606947166.909 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-12-02T22:12:46.909Z,1606947166.909 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:46.909Z,1606947166.909 [Radio_Surface](INFO): Join timeout helper Thread ID is 4048 2020-12-02T22:12:47.017Z,1606947167.017 [Radio_Surface](INFO): Powering down 2020-12-02T22:12:47.018Z,1606947167.018 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:47.018Z,1606947167.018 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:47.022Z,1606947167.022 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-12-02T22:12:47.022Z,1606947167.022 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:47.022Z,1606947167.022 [Onboard](INFO): Join timeout helper Thread ID is 4049 2020-12-02T22:12:48.717Z,1606947168.717 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:48.717Z,1606947168.717 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:48.721Z,1606947168.721 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-12-02T22:12:48.722Z,1606947168.722 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:48.722Z,1606947168.722 [DataOverHttps](INFO): Join timeout helper Thread ID is 4050 2020-12-02T22:12:49.362Z,1606947169.362 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:49.367Z,1606947169.367 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.369Z,1606947169.369 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-12-02T22:12:49.369Z,1606947169.369 [logger ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.370Z,1606947169.370 [logger](INFO): Join timeout helper Thread ID is 4051 2020-12-02T22:12:49.381Z,1606947169.381 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:49.381Z,1606947169.381 [logger ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.389Z,1606947169.389 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-12-02T22:12:49.389Z,1606947169.389 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.389Z,1606947169.389 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-12-02T22:12:49.390Z,1606947169.390 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.390Z,1606947169.390 [controlThread](INFO): Join timeout helper Thread ID is 4052 2020-12-02T22:12:49.477Z,1606947169.477 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-02T22:12:49.477Z,1606947169.477 [controlThread](DEBUG): Uninitializing ControlThread 2020-12-02T22:12:49.477Z,1606947169.477 [AHRS_M2](INFO): Powering down 2020-12-02T22:12:49.549Z,1606947169.549 [NAL9602](INFO): Powering down 2020-12-02T22:12:49.550Z,1606947169.550 [DAT](INFO): Powering down 2020-12-02T22:12:49.671Z,1606947169.671 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-12-02T22:12:49.672Z,1606947169.672 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-12-02T22:12:49.672Z,1606947169.672 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-12-02T22:12:49.673Z,1606947169.673 [MissionManager](INFO): Uninitializing Mission Default 2020-12-02T22:12:49.673Z,1606947169.673 [Default] Stopped 2020-12-02T22:12:49.673Z,1606947169.673 [Default](DEBUG): Aggregate::uninitialize Default 2020-12-02T22:12:49.673Z,1606947169.673 [Default:B.GoToSurface] Stopped 2020-12-02T22:12:49.673Z,1606947169.673 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-12-02T22:12:49.673Z,1606947169.673 [Default:CheckIn] Stopped 2020-12-02T22:12:49.673Z,1606947169.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-12-02T22:12:49.673Z,1606947169.673 [Default:CheckIn:Read_Iridium] Stopped 2020-12-02T22:12:49.676Z,1606947169.676 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-12-02T22:12:49.676Z,1606947169.676 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-12-02T22:12:49.676Z,1606947169.676 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-12-02T22:12:49.676Z,1606947169.676 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-12-02T22:12:49.677Z,1606947169.677 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-12-02T22:12:49.677Z,1606947169.677 [BuoyancyServo](INFO): Powering down 2020-12-02T22:12:49.689Z,1606947169.689 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-12-02T22:12:49.689Z,1606947169.689 [ElevatorServo](INFO): Powering down 2020-12-02T22:12:49.690Z,1606947169.690 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-12-02T22:12:49.690Z,1606947169.690 [MassServo](INFO): Powering down 2020-12-02T22:12:49.691Z,1606947169.691 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-12-02T22:12:49.691Z,1606947169.691 [RudderServo](INFO): Powering down 2020-12-02T22:12:49.691Z,1606947169.691 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-12-02T22:12:49.691Z,1606947169.691 [ThrusterServo](INFO): Powering down 2020-12-02T22:12:49.692Z,1606947169.692 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-12-02T22:12:49.693Z,1606947169.693 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-12-02T22:12:49.693Z,1606947169.693 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-12-02T22:12:49.693Z,1606947169.693 [CBIT](DEBUG): Powering off loads. 2020-12-02T22:12:49.704Z,1606947169.704 [CBIT](DEBUG): Disabling WDT. 2020-12-02T22:12:49.716Z,1606947169.716 [CBIT](DEBUG): Opening all GF detection circuits. 2020-12-02T22:12:49.717Z,1606947169.717 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.830Z,1606947169.830 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.832Z,1606947169.832 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.838Z,1606947169.838 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.888Z,1606947169.888 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.893Z,1606947169.893 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.936Z,1606947169.936 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-12-02T22:12:49.986Z,1606947169.986 [logger ThreadHandler](INFO): Thread cancelled.