2020-05-15T18:31:32.344Z,1589567492.344 [Supervisor](DEBUG): Initializing supervisor.
2020-05-15T18:31:32.347Z,1589567492.347 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-05-15T18:31:32.347Z,1589567492.347 [SyncHandler](INFO): Protected caller Thread ID is 3444
2020-05-15T18:31:32.348Z,1589567492.348 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-05-15T18:31:32.349Z,1589567492.349 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-05-15T18:31:32.349Z,1589567492.349 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3445
2020-05-15T18:31:32.353Z,1589567492.353 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-05-15T18:31:32.365Z,1589567492.365 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-05-15T18:31:32.366Z,1589567492.366 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-05-15T18:31:32.367Z,1589567492.367 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3446
2020-05-15T18:31:32.367Z,1589567492.367 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-05-15T18:31:32.368Z,1589567492.368 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-05-15T18:31:32.369Z,1589567492.369 [logger ThreadHandler](INFO): Protected caller Thread ID is 3447
2020-05-15T18:31:32.371Z,1589567492.371 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-05-15T18:31:32.371Z,1589567492.371 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-05-15T18:31:32.373Z,1589567492.373 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-05-15T18:31:32.706Z,1589567492.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-05-15T18:31:32.707Z,1589567492.707 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-05-15T18:31:33.282Z,1589567493.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-05-15T18:31:33.283Z,1589567493.283 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-05-15T18:31:33.420Z,1589567493.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-05-15T18:31:33.421Z,1589567493.421 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-05-15T18:31:33.929Z,1589567493.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-05-15T18:31:33.929Z,1589567493.929 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-05-15T18:31:34.027Z,1589567494.027 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-05-15T18:31:34.028Z,1589567494.028 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-05-15T18:31:34.109Z,1589567494.109 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-05-15T18:31:34.400Z,1589567494.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-05-15T18:31:34.401Z,1589567494.401 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-05-15T18:31:34.546Z,1589567494.546 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-05-15T18:31:34.547Z,1589567494.547 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-05-15T18:31:34.648Z,1589567494.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-05-15T18:31:34.649Z,1589567494.649 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-05-15T18:31:35.115Z,1589567495.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-05-15T18:31:35.116Z,1589567495.116 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-05-15T18:31:35.509Z,1589567495.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-05-15T18:31:35.509Z,1589567495.509 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-05-15T18:31:35.610Z,1589567495.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-05-15T18:31:35.611Z,1589567495.611 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-05-15T18:31:35.793Z,1589567495.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-05-15T18:31:35.794Z,1589567495.794 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-05-15T18:31:35.991Z,1589567495.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-05-15T18:31:35.992Z,1589567495.992 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-05-15T18:31:36.240Z,1589567496.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-05-15T18:31:36.242Z,1589567496.242 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2020-05-15T18:31:36.243Z,1589567496.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2020-05-15T18:31:36.695Z,1589567496.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2020-05-15T18:31:36.829Z,1589567496.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2020-05-15T18:31:36.913Z,1589567496.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2020-05-15T18:31:36.995Z,1589567496.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2020-05-15T18:31:37.098Z,1589567497.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2020-05-15T18:31:37.274Z,1589567497.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2020-05-15T18:31:37.497Z,1589567497.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-05-15T18:31:37.498Z,1589567497.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2020-05-15T18:31:37.605Z,1589567497.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2020-05-15T18:31:37.698Z,1589567497.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2020-05-15T18:31:37.794Z,1589567497.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2020-05-15T18:31:37.887Z,1589567497.887 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2020-05-15T18:31:37.887Z,1589567497.887 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-05-15T18:31:37.895Z,1589567497.895 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-05-15T18:31:37.925Z,1589567497.925 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-05-15T18:31:37.926Z,1589567497.926 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-05-15T18:31:37.981Z,1589567497.981 [DepthRateCalculator] Loaded
2020-05-15T18:31:37.981Z,1589567497.981 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-05-15T18:31:37.987Z,1589567497.987 [PitchRateCalculator] Loaded
2020-05-15T18:31:37.987Z,1589567497.987 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-05-15T18:31:38.003Z,1589567498.003 [SpeedCalculator] Loaded
2020-05-15T18:31:38.004Z,1589567498.004 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-05-15T18:31:38.025Z,1589567498.025 [TempGradientCalculator] Loaded
2020-05-15T18:31:38.025Z,1589567498.025 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-05-15T18:31:38.030Z,1589567498.030 [YawRateCalculator] Loaded
2020-05-15T18:31:38.031Z,1589567498.031 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-05-15T18:31:38.071Z,1589567498.071 [ElevatorOffsetCalculator] Loaded
2020-05-15T18:31:38.071Z,1589567498.071 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-05-15T18:31:38.071Z,1589567498.071 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-05-15T18:31:38.072Z,1589567498.072 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-05-15T18:31:38.137Z,1589567498.137 [VerticalControl](DEBUG): Construct VerticalControl.
2020-05-15T18:31:38.237Z,1589567498.237 [VerticalControl] Loaded
2020-05-15T18:31:38.237Z,1589567498.237 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-05-15T18:31:38.238Z,1589567498.238 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-05-15T18:31:38.318Z,1589567498.318 [HorizontalControl] Loaded
2020-05-15T18:31:38.318Z,1589567498.318 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-05-15T18:31:38.319Z,1589567498.319 [SpeedControl](DEBUG): Construct SpeedControl.
2020-05-15T18:31:38.324Z,1589567498.324 [SpeedControl] Loaded
2020-05-15T18:31:38.324Z,1589567498.324 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-05-15T18:31:38.325Z,1589567498.325 [LoopControl](DEBUG): Construct LoopControl.
2020-05-15T18:31:38.325Z,1589567498.325 [LoopControl] Loaded
2020-05-15T18:31:38.326Z,1589567498.326 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-05-15T18:31:38.326Z,1589567498.326 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-05-15T18:31:38.327Z,1589567498.327 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-05-15T18:31:38.446Z,1589567498.446 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-05-15T18:31:38.446Z,1589567498.446 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-05-15T18:31:38.467Z,1589567498.467 [NavChart] Loaded
2020-05-15T18:31:38.467Z,1589567498.467 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-05-15T18:31:38.471Z,1589567498.471 [UniversalFixResidualReporter] Loaded
2020-05-15T18:31:38.471Z,1589567498.471 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-05-15T18:31:38.471Z,1589567498.471 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-05-15T18:31:38.472Z,1589567498.472 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-05-15T18:31:38.598Z,1589567498.598 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-05-15T18:31:38.599Z,1589567498.599 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-05-15T18:31:39.125Z,1589567499.125 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-05-15T18:31:39.130Z,1589567499.130 [AHRS_M2](INFO): created writer for : platform_orientation
2020-05-15T18:31:39.132Z,1589567499.132 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-05-15T18:31:39.138Z,1589567499.138 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-05-15T18:31:39.138Z,1589567499.138 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-05-15T18:31:39.143Z,1589567499.143 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-05-15T18:31:39.144Z,1589567499.144 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-05-15T18:31:39.149Z,1589567499.149 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-05-15T18:31:39.220Z,1589567499.220 [AHRS_M2] Loaded
2020-05-15T18:31:39.221Z,1589567499.221 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-05-15T18:31:39.320Z,1589567499.320 [DataOverHttps] Loaded
2020-05-15T18:31:39.320Z,1589567499.320 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-05-15T18:31:39.321Z,1589567499.321 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408244E0
2020-05-15T18:31:39.322Z,1589567499.322 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3527
2020-05-15T18:31:39.335Z,1589567499.335 [Depth_Keller] Loaded
2020-05-15T18:31:39.335Z,1589567499.335 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-05-15T18:31:39.340Z,1589567499.340 [DropWeight] Loaded
2020-05-15T18:31:39.340Z,1589567499.340 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-05-15T18:31:39.386Z,1589567499.386 [DVL_micro] Loaded
2020-05-15T18:31:39.386Z,1589567499.386 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-05-15T18:31:39.487Z,1589567499.487 [NAL9602] Loaded
2020-05-15T18:31:39.488Z,1589567499.488 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-05-15T18:31:39.537Z,1589567499.537 [Onboard] Loaded
2020-05-15T18:31:39.538Z,1589567499.538 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-05-15T18:31:39.541Z,1589567499.541 [Radio_Surface] Loaded
2020-05-15T18:31:39.541Z,1589567499.541 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-05-15T18:31:39.542Z,1589567499.542 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408544E0
2020-05-15T18:31:39.542Z,1589567499.542 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3528
2020-05-15T18:31:39.676Z,1589567499.676 [DAT] Loaded
2020-05-15T18:31:39.677Z,1589567499.677 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-05-15T18:31:41.193Z,1589567501.193 [BPC1] Loaded
2020-05-15T18:31:41.193Z,1589567501.193 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-05-15T18:31:41.193Z,1589567501.193 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-05-15T18:31:41.194Z,1589567501.194 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-05-15T18:31:41.323Z,1589567501.323 [BuoyancyServo] Loaded
2020-05-15T18:31:41.324Z,1589567501.324 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-05-15T18:31:41.335Z,1589567501.335 [ElevatorServo] Loaded
2020-05-15T18:31:41.335Z,1589567501.335 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-05-15T18:31:41.346Z,1589567501.346 [MassServo] Loaded
2020-05-15T18:31:41.346Z,1589567501.346 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-05-15T18:31:41.357Z,1589567501.357 [RudderServo] Loaded
2020-05-15T18:31:41.358Z,1589567501.358 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-05-15T18:31:41.368Z,1589567501.368 [ThrusterServo] Loaded
2020-05-15T18:31:41.369Z,1589567501.369 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-05-15T18:31:41.369Z,1589567501.369 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-05-15T18:31:41.370Z,1589567501.370 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-05-15T18:31:41.933Z,1589567501.933 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-05-15T18:31:41.934Z,1589567501.934 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-05-15T18:31:41.995Z,1589567501.995 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-05-15T18:31:41.996Z,1589567501.996 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-05-15T18:31:42.366Z,1589567502.366 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-05-15T18:31:42.367Z,1589567502.367 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-05-15T18:31:42.640Z,1589567502.640 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-05-15T18:31:42.645Z,1589567502.645 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-05-15T18:31:42.646Z,1589567502.646 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-05-15T18:31:42.651Z,1589567502.651 [CTD_Seabird](INFO): created writer for : depth
2020-05-15T18:31:42.651Z,1589567502.651 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-05-15T18:31:42.656Z,1589567502.656 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-05-15T18:31:42.657Z,1589567502.657 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-05-15T18:31:42.662Z,1589567502.662 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-05-15T18:31:42.662Z,1589567502.662 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-05-15T18:31:42.667Z,1589567502.667 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-05-15T18:31:42.668Z,1589567502.668 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-05-15T18:31:42.674Z,1589567502.674 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-05-15T18:31:42.674Z,1589567502.674 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-05-15T18:31:42.680Z,1589567502.680 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-05-15T18:31:42.707Z,1589567502.707 [CTD_Seabird] Loaded
2020-05-15T18:31:42.707Z,1589567502.707 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-05-15T18:31:42.708Z,1589567502.708 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6A4E0
2020-05-15T18:31:42.709Z,1589567502.709 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3529
2020-05-15T18:31:42.739Z,1589567502.739 [ESPComponent] Loaded
2020-05-15T18:31:42.739Z,1589567502.739 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-05-15T18:31:42.754Z,1589567502.754 [PAR_Licor] Loaded
2020-05-15T18:31:42.754Z,1589567502.754 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-05-15T18:31:42.760Z,1589567502.760 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-05-15T18:31:42.761Z,1589567502.761 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-05-15T18:31:42.765Z,1589567502.765 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-05-15T18:31:42.765Z,1589567502.765 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-05-15T18:31:42.770Z,1589567502.770 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-05-15T18:31:42.770Z,1589567502.770 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-05-15T18:31:42.774Z,1589567502.774 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-05-15T18:31:42.774Z,1589567502.774 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-05-15T18:31:42.779Z,1589567502.779 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-05-15T18:31:42.779Z,1589567502.779 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-05-15T18:31:42.783Z,1589567502.783 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-05-15T18:31:42.783Z,1589567502.783 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-05-15T18:31:42.788Z,1589567502.788 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-05-15T18:31:42.788Z,1589567502.788 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-05-15T18:31:42.792Z,1589567502.792 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.797Z,1589567502.797 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.797Z,1589567502.797 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.798Z,1589567502.798 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.802Z,1589567502.802 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.802Z,1589567502.802 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.806Z,1589567502.806 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.806Z,1589567502.806 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-05-15T18:31:42.811Z,1589567502.811 [WetLabsBB2FL] Loaded
2020-05-15T18:31:42.811Z,1589567502.811 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-05-15T18:31:42.812Z,1589567502.812 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0
2020-05-15T18:31:42.813Z,1589567502.813 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3530
2020-05-15T18:31:42.813Z,1589567502.813 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-05-15T18:31:42.814Z,1589567502.814 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-05-15T18:31:42.977Z,1589567502.977 [SBIT](DEBUG): Construct Startup Built In Test.
2020-05-15T18:31:42.988Z,1589567502.988 [SBIT] Loaded
2020-05-15T18:31:42.989Z,1589567502.989 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-05-15T18:31:42.989Z,1589567502.989 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-05-15T18:31:43.002Z,1589567503.002 [IBIT] Loaded
2020-05-15T18:31:43.002Z,1589567503.002 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-05-15T18:31:43.005Z,1589567503.005 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-05-15T18:31:43.144Z,1589567503.144 [CBIT] Loaded
2020-05-15T18:31:43.144Z,1589567503.144 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-05-15T18:31:43.145Z,1589567503.145 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-05-15T18:31:43.148Z,1589567503.148 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-05-15T18:31:43.149Z,1589567503.149 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-05-15T18:31:43.156Z,1589567503.156 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-05-15T18:31:43.157Z,1589567503.157 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B054E0
2020-05-15T18:31:43.157Z,1589567503.157 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3531
2020-05-15T18:31:43.162Z,1589567503.162 [Supervisor](INFO): Main Thread ID is 3443
2020-05-15T18:31:43.162Z,1589567503.162 [Supervisor](DEBUG): Running supervisor.
2020-05-15T18:31:43.163Z,1589567503.163 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3532
2020-05-15T18:31:43.168Z,1589567503.168 [controlThread ThreadHandler](INFO): Handler Thread ID is 3533
2020-05-15T18:31:43.169Z,1589567503.169 [controlThread](DEBUG): Initializing ControlThread
2020-05-15T18:31:43.169Z,1589567503.169 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-05-15T18:31:43.170Z,1589567503.170 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-05-15T18:31:43.170Z,1589567503.170 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-05-15T18:31:43.170Z,1589567503.170 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-05-15T18:31:43.171Z,1589567503.171 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-05-15T18:31:43.171Z,1589567503.171 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-05-15T18:31:43.172Z,1589567503.172 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-05-15T18:31:43.174Z,1589567503.174 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-05-15T18:31:43.174Z,1589567503.174 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-05-15T18:31:43.175Z,1589567503.175 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-05-15T18:31:43.176Z,1589567503.176 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-05-15T18:31:43.176Z,1589567503.176 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-05-15T18:31:43.188Z,1589567503.188 [SBIT](INFO): Initialize SBIT Component.
2020-05-15T18:31:43.188Z,1589567503.188 [SBIT](IMPORTANT): git: 2020-02-26-39-g5ba86ef19
2020-05-15T18:31:43.188Z,1589567503.188 [SBIT](INFO): git hash: 5ba86ef19446a3a7971077fddc96013a7a660a2f
2020-05-15T18:31:43.189Z,1589567503.189 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-05-15T18:31:43.189Z,1589567503.189 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2020-05-15T18:31:43.190Z,1589567503.190 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2020-05-15T18:31:43.191Z,1589567503.191 [IBIT](INFO): Initialize IBIT Component.
2020-05-15T18:31:43.192Z,1589567503.192 [logger ThreadHandler](INFO): Handler Thread ID is 3534
2020-05-15T18:31:43.208Z,1589567503.208 [CBIT](DEBUG): Initialize CBIT Component.
2020-05-15T18:31:43.216Z,1589567503.216 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3535
2020-05-15T18:31:43.217Z,1589567503.217 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-05-15T18:31:43.220Z,1589567503.220 [CBIT](DEBUG): Initialized mux pins.
2020-05-15T18:31:43.220Z,1589567503.220 [CBIT](DEBUG): Initializing the watchdog timer.
2020-05-15T18:31:43.228Z,1589567503.228 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3536
2020-05-15T18:31:43.240Z,1589567503.240 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3537
2020-05-15T18:31:43.240Z,1589567503.240 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-05-15T18:31:43.244Z,1589567503.244 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-05-15T18:31:43.244Z,1589567503.244 [CBIT](DEBUG): Initializing heartbeat.
2020-05-15T18:31:43.244Z,1589567503.244 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-05-15T18:31:43.246Z,1589567503.246 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3539
2020-05-15T18:31:43.246Z,1589567503.246 [WetLabsBB2FL](INFO): Powering down
2020-05-15T18:31:43.292Z,1589567503.292 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3540
2020-05-15T18:31:43.315Z,1589567503.315 [CBIT](DEBUG): Deactivating GF circuits.
2020-05-15T18:31:43.316Z,1589567503.316 [CBIT](DEBUG): Deactivating emergency mode.
2020-05-15T18:31:43.385Z,1589567503.385 [CBIT](DEBUG): Backplane powered.
2020-05-15T18:31:43.386Z,1589567503.386 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-05-15T18:31:43.395Z,1589567503.395 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-05-15T18:31:43.396Z,1589567503.396 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-05-15T18:31:43.396Z,1589567503.396 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-05-15T18:31:43.396Z,1589567503.396 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-05-15T18:31:43.396Z,1589567503.396 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-05-15T18:31:43.396Z,1589567503.396 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-05-15T18:31:43.397Z,1589567503.397 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-05-15T18:31:43.397Z,1589567503.397 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-05-15T18:31:43.397Z,1589567503.397 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-05-15T18:31:43.397Z,1589567503.397 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-05-15T18:31:43.397Z,1589567503.397 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-05-15T18:31:43.398Z,1589567503.398 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-05-15T18:31:43.398Z,1589567503.398 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-05-15T18:31:43.398Z,1589567503.398 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-05-15T18:31:43.398Z,1589567503.398 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-05-15T18:31:43.398Z,1589567503.398 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-05-15T18:31:43.422Z,1589567503.422 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-05-15T18:31:43.547Z,1589567503.547 [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-05-15T18:31:43.548Z,1589567503.548 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-05-15T18:31:43.632Z,1589567503.632 [Radio_Surface](INFO): Powering up
2020-05-15T18:31:43.766Z,1589567503.766 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-05-15T18:31:43.767Z,1589567503.767 [Default:A.Wait](DEBUG): Construct Wait.
2020-05-15T18:31:43.769Z,1589567503.769 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-05-15T18:31:43.845Z,1589567503.845 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-05-15T18:31:43.847Z,1589567503.847 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-05-15T18:31:43.868Z,1589567503.868 [Default:E.Execute](DEBUG): Construct Execute.
2020-05-15T18:31:43.871Z,1589567503.871 [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-05-15T18:31:43.896Z,1589567503.896 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-05-15T18:31:43.907Z,1589567503.907 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-05-15T18:31:44.610Z,1589567504.610 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-05-15T18:31:44.610Z,1589567504.610 [DAT](INFO): Powering up
2020-05-15T18:31:44.610Z,1589567504.610 [DAT](DEBUG): Initializing DAT.
2020-05-15T18:31:44.684Z,1589567504.684 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-05-15T18:31:44.749Z,1589567504.749 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-05-15T18:31:44.760Z,1589567504.760 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-05-15T18:31:44.761Z,1589567504.761 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-05-15T18:31:44.772Z,1589567504.772 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-05-15T18:31:44.773Z,1589567504.773 [MassServo](DEBUG): Initializing EZServoServo.
2020-05-15T18:31:44.784Z,1589567504.784 [MassServo](DEBUG): Initializing MassServo.
2020-05-15T18:31:44.785Z,1589567504.785 [RudderServo](DEBUG): Initializing EZServoServo.
2020-05-15T18:31:44.796Z,1589567504.796 [RudderServo](DEBUG): Initializing RudderServo.
2020-05-15T18:31:44.797Z,1589567504.797 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-05-15T18:31:44.808Z,1589567504.808 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-05-15T18:31:44.916Z,1589567504.916 [Depth_Keller](ERROR): Pressure reading out of range: 1829.799438 decibar
2020-05-15T18:31:47.972Z,1589567507.972 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:48.407Z,1589567508.407 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:48.935Z,1589567508.935 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:49.556Z,1589567509.556 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:50.151Z,1589567510.151 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:50.608Z,1589567510.608 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:51.351Z,1589567511.351 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:51.788Z,1589567511.788 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:52.284Z,1589567512.284 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:52.699Z,1589567512.699 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:53.168Z,1589567513.168 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:53.191Z,1589567513.191 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:31:53.194Z,1589567513.194 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:31:53.201Z,1589567513.201 [Onboard](IMPORTANT): Battery Current:10.941000 mA
2020-05-15T18:31:53.201Z,1589567513.201 [Onboard](IMPORTANT): average counter: 1
2020-05-15T18:31:53.201Z,1589567513.201 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:31:53.202Z,1589567513.202 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:31:53.603Z,1589567513.603 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:54.047Z,1589567514.047 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:54.495Z,1589567514.495 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:54.956Z,1589567514.956 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:55.380Z,1589567515.380 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:55.811Z,1589567515.811 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:56.219Z,1589567516.219 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:56.292Z,1589567516.292 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-05-15T18:31:56.292Z,1589567516.292 [CTD_Seabird] Communications Fault, FailCount= 1
2020-05-15T18:31:56.292Z,1589567516.292 [CTD_Seabird](ERROR): Communications Fault
2020-05-15T18:31:56.307Z,1589567516.307 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-05-15T18:31:56.640Z,1589567516.640 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:56.699Z,1589567516.699 [CTD_Seabird](INFO): Powering down
2020-05-15T18:31:57.063Z,1589567517.063 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:57.459Z,1589567517.459 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:31:57.460Z,1589567517.460 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-05-15T18:31:57.460Z,1589567517.460 [AHRS_M2] Communications Fault, FailCount= 1
2020-05-15T18:31:57.460Z,1589567517.460 [AHRS_M2](ERROR): Communications Fault
2020-05-15T18:31:57.575Z,1589567517.575 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-05-15T18:31:57.596Z,1589567517.596 [AHRS_M2](INFO): Powering down
2020-05-15T18:31:57.673Z,1589567517.673 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.051374
2020-05-15T18:31:57.852Z,1589567517.852 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-05-15T18:31:57.852Z,1589567517.852 [CTD_Seabird] No Fault, FailCount= 1
2020-05-15T18:31:58.068Z,1589567518.068 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-05-15T18:31:58.069Z,1589567518.069 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-05-15T18:31:58.471Z,1589567518.471 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-05-15T18:31:58.471Z,1589567518.471 [AHRS_M2] No Fault, FailCount= 1
2020-05-15T18:31:58.799Z,1589567518.799 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-05-15T18:32:02.355Z,1589567522.355 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:02.361Z,1589567522.361 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:02.361Z,1589567522.361 [DVL_micro] Communications Fault, FailCount= 1
2020-05-15T18:32:02.361Z,1589567522.361 [DVL_micro](ERROR): Communications Fault
2020-05-15T18:32:02.435Z,1589567522.435 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-05-15T18:32:02.768Z,1589567522.768 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:02.844Z,1589567522.844 [DVL_micro](INFO): Powering down
2020-05-15T18:32:03.335Z,1589567523.335 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:03.358Z,1589567523.358 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:32:03.368Z,1589567523.368 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:32:03.374Z,1589567523.374 [Onboard](IMPORTANT): Battery Current:7.815000 mA
2020-05-15T18:32:03.374Z,1589567523.374 [Onboard](IMPORTANT): average counter: 2
2020-05-15T18:32:03.374Z,1589567523.374 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:32:03.375Z,1589567523.375 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:32:03.876Z,1589567523.876 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:03.946Z,1589567523.946 [SBIT](IMPORTANT): Beginning Startup BIT
2020-05-15T18:32:03.957Z,1589567523.957 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-05-15T18:32:03.957Z,1589567523.957 [DVL_micro] No Fault, FailCount= 1
2020-05-15T18:32:03.958Z,1589567523.958 [CBIT](IMPORTANT): Beginning ground fault scan
2020-05-15T18:32:04.295Z,1589567524.295 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:04.848Z,1589567524.848 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:05.371Z,1589567525.371 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:05.959Z,1589567525.959 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:06.483Z,1589567526.483 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:07.443Z,1589567527.443 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:07.972Z,1589567527.972 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:08.667Z,1589567528.667 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:09.171Z,1589567529.171 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:09.843Z,1589567529.843 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:09.849Z,1589567529.849 [NAL9602](INFO): Powering up NAL9602
2020-05-15T18:32:10.295Z,1589567530.295 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:10.843Z,1589567530.843 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:11.120Z,1589567531.120 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:11.120Z,1589567531.120 [CTD_Seabird] Communications Fault, FailCount= 2
2020-05-15T18:32:11.120Z,1589567531.120 [CTD_Seabird](ERROR): Communications Fault
2020-05-15T18:32:11.260Z,1589567531.260 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:11.337Z,1589567531.337 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-05-15T18:32:11.524Z,1589567531.524 [CTD_Seabird](INFO): Powering down
2020-05-15T18:32:11.759Z,1589567531.759 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:11.760Z,1589567531.760 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:11.760Z,1589567531.760 [AHRS_M2] Communications Fault, FailCount= 2
2020-05-15T18:32:11.760Z,1589567531.760 [AHRS_M2](ERROR): Communications Fault
2020-05-15T18:32:11.867Z,1589567531.867 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-05-15T18:32:11.910Z,1589567531.910 [AHRS_M2](INFO): Powering down
2020-05-15T18:32:12.791Z,1589567532.791 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-05-15T18:32:12.791Z,1589567532.791 [AHRS_M2] No Fault, FailCount= 2
2020-05-15T18:32:12.791Z,1589567532.791 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-05-15T18:32:12.791Z,1589567532.791 [CTD_Seabird] No Fault, FailCount= 2
2020-05-15T18:32:12.868Z,1589567532.868 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-05-15T18:32:12.872Z,1589567532.872 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-05-15T18:32:13.119Z,1589567533.119 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-05-15T18:32:13.549Z,1589567533.549 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:32:13.553Z,1589567533.553 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:32:13.559Z,1589567533.559 [Onboard](IMPORTANT): Battery Current:8.336000 mA
2020-05-15T18:32:13.559Z,1589567533.559 [Onboard](IMPORTANT): average counter: 3
2020-05-15T18:32:13.559Z,1589567533.559 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:32:13.560Z,1589567533.560 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:32:15.603Z,1589567535.603 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.025185
CHAN A1 (24V): 0.003072
CHAN A2 (12V): -0.003433
CHAN A3 (5V): -0.000953
CHAN B0 (3.3V): -0.001246
CHAN B1 (3.15aV): -0.001259
CHAN B2 (3.15bV): -0.001353
CHAN B3 (GND): -0.001118
OPEN: -0.004964
Full Scale Calc: 4.765 mA, -1.589 mA
2020-05-15T18:32:16.675Z,1589567536.675 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:17.095Z,1589567537.095 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:17.499Z,1589567537.499 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:17.923Z,1589567537.923 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:18.355Z,1589567538.355 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:18.791Z,1589567538.791 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:19.223Z,1589567539.223 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:20.159Z,1589567540.159 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:20.663Z,1589567540.663 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:21.212Z,1589567541.212 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:21.217Z,1589567541.217 [NAL9602](INFO): NAL9602 initialized
2020-05-15T18:32:21.719Z,1589567541.719 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:22.235Z,1589567542.235 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:22.241Z,1589567542.241 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:22.241Z,1589567542.241 [DVL_micro] Communications Fault, FailCount= 2
2020-05-15T18:32:22.241Z,1589567542.241 [DVL_micro](ERROR): Communications Fault
2020-05-15T18:32:22.452Z,1589567542.452 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-05-15T18:32:22.772Z,1589567542.772 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:22.844Z,1589567542.844 [DVL_micro](INFO): Powering down
2020-05-15T18:32:23.396Z,1589567543.396 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:23.876Z,1589567543.876 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:23.921Z,1589567543.921 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:32:23.925Z,1589567543.925 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:32:23.932Z,1589567543.932 [Onboard](IMPORTANT): Battery Current:9.899000 mA
2020-05-15T18:32:23.932Z,1589567543.932 [Onboard](IMPORTANT): average counter: 4
2020-05-15T18:32:23.932Z,1589567543.932 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:32:23.932Z,1589567543.932 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:32:24.144Z,1589567544.144 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-05-15T18:32:24.144Z,1589567544.144 [DVL_micro] No Fault, FailCount= 2
2020-05-15T18:32:24.492Z,1589567544.492 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:25.024Z,1589567545.024 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:25.728Z,1589567545.728 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:25.920Z,1589567545.920 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:25.920Z,1589567545.920 [CTD_Seabird] Communications Fault, FailCount= 3
2020-05-15T18:32:25.920Z,1589567545.920 [CTD_Seabird](ERROR): Communications Fault
2020-05-15T18:32:25.990Z,1589567545.990 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-05-15T18:32:25.990Z,1589567545.990 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2020-05-15T18:32:26.324Z,1589567546.324 [CTD_Seabird](INFO): Powering down
2020-05-15T18:32:26.339Z,1589567546.339 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:26.340Z,1589567546.340 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:26.340Z,1589567546.340 [AHRS_M2] Communications Fault, FailCount= 3
2020-05-15T18:32:26.340Z,1589567546.340 [AHRS_M2](ERROR): Communications Fault
2020-05-15T18:32:26.510Z,1589567546.510 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-05-15T18:32:26.515Z,1589567546.515 [AHRS_M2](INFO): Powering down
2020-05-15T18:32:27.404Z,1589567547.404 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-05-15T18:32:27.404Z,1589567547.404 [AHRS_M2] No Fault, FailCount= 3
2020-05-15T18:32:27.727Z,1589567547.727 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-05-15T18:32:31.275Z,1589567551.275 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:31.687Z,1589567551.687 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:32.099Z,1589567552.099 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:32.511Z,1589567552.511 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:32.931Z,1589567552.931 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:33.344Z,1589567553.344 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:33.759Z,1589567553.759 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:34.188Z,1589567554.188 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:34.232Z,1589567554.232 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:32:34.236Z,1589567554.236 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:32:34.242Z,1589567554.242 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:32:34.242Z,1589567554.242 [Onboard](IMPORTANT): average counter: 5
2020-05-15T18:32:34.242Z,1589567554.242 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:32:34.242Z,1589567554.242 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:32:34.735Z,1589567554.735 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:35.191Z,1589567555.191 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:35.635Z,1589567555.635 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:36.079Z,1589567556.079 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:36.527Z,1589567556.527 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:36.967Z,1589567556.967 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:37.411Z,1589567557.411 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:37.832Z,1589567557.832 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:38.240Z,1589567558.240 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:38.682Z,1589567558.682 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:39.127Z,1589567559.127 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:39.547Z,1589567559.547 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:39.995Z,1589567559.995 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:40.419Z,1589567560.419 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:40.839Z,1589567560.839 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:40.840Z,1589567560.840 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:40.840Z,1589567560.840 [AHRS_M2] Communications Fault, FailCount= 4
2020-05-15T18:32:40.840Z,1589567560.840 [AHRS_M2](ERROR): Communications Fault
2020-05-15T18:32:40.962Z,1589567560.962 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-05-15T18:32:40.967Z,1589567560.967 [AHRS_M2](INFO): Powering down
2020-05-15T18:32:41.776Z,1589567561.776 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:41.777Z,1589567561.777 [DVL_micro] Communications Fault, FailCount= 3
2020-05-15T18:32:41.777Z,1589567561.777 [DVL_micro](ERROR): Communications Fault
2020-05-15T18:32:41.904Z,1589567561.904 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-05-15T18:32:41.904Z,1589567561.904 [AHRS_M2] No Fault, FailCount= 4
2020-05-15T18:32:41.904Z,1589567561.904 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-05-15T18:32:41.905Z,1589567561.905 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-05-15T18:32:42.175Z,1589567562.175 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-05-15T18:32:42.288Z,1589567562.288 [DVL_micro](INFO): Powering down
2020-05-15T18:32:42.386Z,1589567562.386 [CommandLine](FAULT): Scheduling is paused
2020-05-15T18:32:42.387Z,1589567562.387 [CBIT](INFO): Critical error at 20200515T183241
2020-05-15T18:32:42.387Z,1589567562.387 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-05-15T18:32:44.622Z,1589567564.622 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:32:44.656Z,1589567564.656 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:32:44.663Z,1589567564.663 [Onboard](IMPORTANT): Battery Current:7.815000 mA
2020-05-15T18:32:44.663Z,1589567564.663 [Onboard](IMPORTANT): average counter: 6
2020-05-15T18:32:44.663Z,1589567564.663 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:32:44.664Z,1589567564.664 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:32:44.771Z,1589567564.771 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-05-15T18:32:44.771Z,1589567564.771 [DAT] Communications Fault, FailCount= 1
2020-05-15T18:32:44.771Z,1589567564.771 [DAT](ERROR): Communications Fault
2020-05-15T18:32:44.866Z,1589567564.866 [CBIT](ERROR): Communications Fault in component: DAT
2020-05-15T18:32:45.028Z,1589567565.028 [DAT](INFO): Powering down
2020-05-15T18:32:45.731Z,1589567565.731 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:46.163Z,1589567566.163 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:46.388Z,1589567566.388 [CBIT](INFO): Clearing failed state for component DAT
2020-05-15T18:32:46.388Z,1589567566.388 [DAT] No Fault, FailCount= 1
2020-05-15T18:32:46.716Z,1589567566.716 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:47.555Z,1589567567.555 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:48.055Z,1589567568.055 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:48.122Z,1589567568.122 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-05-15T18:32:48.123Z,1589567568.123 [DAT](INFO): Powering up
2020-05-15T18:32:48.123Z,1589567568.123 [DAT](DEBUG): Initializing DAT.
2020-05-15T18:32:48.196Z,1589567568.196 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-05-15T18:32:48.703Z,1589567568.703 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:49.215Z,1589567569.215 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:49.787Z,1589567569.787 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:50.407Z,1589567570.407 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:51.052Z,1589567571.052 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:51.491Z,1589567571.491 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:51.915Z,1589567571.915 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:52.316Z,1589567572.316 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:52.719Z,1589567572.719 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:53.123Z,1589567573.123 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:53.135Z,1589567573.135 [CommandLine](IMPORTANT): got command burn 
2020-05-15T18:32:53.135Z,1589567573.135 [CommandLine](FAULT): Incomplete syntax. Try: help burn
2020-05-15T18:32:53.551Z,1589567573.551 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:53.955Z,1589567573.955 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:54.371Z,1589567574.371 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:54.771Z,1589567574.771 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:54.792Z,1589567574.792 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:32:54.796Z,1589567574.796 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:32:54.802Z,1589567574.802 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:32:54.802Z,1589567574.802 [Onboard](IMPORTANT): average counter: 0
2020-05-15T18:32:54.802Z,1589567574.802 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:32:54.803Z,1589567574.803 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:32:55.207Z,1589567575.207 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-05-15T18:32:55.208Z,1589567575.208 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-05-15T18:32:55.208Z,1589567575.208 [AHRS_M2] Communications Fault, FailCount= 5
2020-05-15T18:32:55.208Z,1589567575.208 [AHRS_M2](ERROR): Communications Fault
2020-05-15T18:32:55.250Z,1589567575.250 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-05-15T18:32:55.251Z,1589567575.251 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-05-15T18:32:55.302Z,1589567575.302 [AHRS_M2](INFO): Powering down
2020-05-15T18:32:55.409Z,1589567575.409 [CBIT](INFO): Critical error at 20200515T183255
2020-05-15T18:32:57.786Z,1589567577.786 [SBIT](IMPORTANT): SBIT PASSED
2020-05-15T18:32:57.786Z,1589567577.786 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-05-15T18:32:57.787Z,1589567577.787 [SBIT](IMPORTANT): 

2020-05-15T18:32:57.791Z,1589567577.791 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;

2020-05-15T18:32:57.792Z,1589567577.792 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-05-15T18:32:57.792Z,1589567577.792 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2020-05-15T18:32:58.176Z,1589567578.176 [MissionManager](IMPORTANT): Started mission Startup
2020-05-15T18:32:58.177Z,1589567578.177 [Startup] Running Loop=1
2020-05-15T18:32:58.177Z,1589567578.177 [Startup](DEBUG): Aggregate::initialize Startup
2020-05-15T18:32:58.177Z,1589567578.177 [Startup:A.GoToSurface] Running Loop=1
2020-05-15T18:32:58.177Z,1589567578.177 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-05-15T18:32:58.178Z,1589567578.178 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-05-15T18:32:58.178Z,1589567578.178 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-05-15T18:32:58.178Z,1589567578.178 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-05-15T18:32:58.179Z,1589567578.179 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-05-15T18:32:58.179Z,1589567578.179 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-05-15T18:32:58.181Z,1589567578.181 [Startup:StartupSatComms] Running Loop=1
2020-05-15T18:32:58.181Z,1589567578.181 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-05-15T18:32:58.181Z,1589567578.181 [Startup:StartupSatComms:A] Running Loop=1
2020-05-15T18:32:58.593Z,1589567578.593 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-05-15T18:32:58.870Z,1589567578.870 [CommandLine](INFO): End of History
2020-05-15T18:32:59.638Z,1589567579.638 [CommandLine](INFO): End of History
2020-05-15T18:33:00.474Z,1589567580.474 [CommandLine](INFO): End of History
2020-05-15T18:33:01.194Z,1589567581.194 [CommandLine](INFO): End of History
2020-05-15T18:33:01.794Z,1589567581.794 [CommandLine](INFO): End of History
2020-05-15T18:33:02.602Z,1589567582.602 [CommandLine](INFO): End of History
2020-05-15T18:33:02.806Z,1589567582.806 [CommandLine](INFO): End of History
2020-05-15T18:33:02.970Z,1589567582.970 [CommandLine](INFO): End of History
2020-05-15T18:33:03.590Z,1589567583.590 [CommandLine](INFO): End of History
2020-05-15T18:33:03.790Z,1589567583.790 [CommandLine](INFO): End of History
2020-05-15T18:33:03.970Z,1589567583.970 [CommandLine](INFO): End of History
2020-05-15T18:33:05.019Z,1589567585.019 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:33:05.022Z,1589567585.022 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:33:05.078Z,1589567585.078 [Onboard](IMPORTANT): Battery Current:6.773000 mA
2020-05-15T18:33:05.078Z,1589567585.078 [Onboard](IMPORTANT): average counter: 1
2020-05-15T18:33:05.079Z,1589567585.079 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:33:05.079Z,1589567585.079 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:33:15.118Z,1589567595.118 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:33:15.121Z,1589567595.121 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:33:15.127Z,1589567595.127 [Onboard](IMPORTANT): Battery Current:7.815000 mA
2020-05-15T18:33:15.127Z,1589567595.127 [Onboard](IMPORTANT): average counter: 2
2020-05-15T18:33:15.127Z,1589567595.127 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:33:15.128Z,1589567595.128 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:33:25.233Z,1589567605.233 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:33:25.236Z,1589567605.236 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:33:25.242Z,1589567605.242 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:33:25.242Z,1589567605.242 [Onboard](IMPORTANT): average counter: 3
2020-05-15T18:33:25.242Z,1589567605.242 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:33:25.243Z,1589567605.243 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:33:35.333Z,1589567615.333 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:33:35.336Z,1589567615.336 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:33:35.419Z,1589567615.419 [Onboard](IMPORTANT): Battery Current:6.773000 mA
2020-05-15T18:33:35.419Z,1589567615.419 [Onboard](IMPORTANT): average counter: 4
2020-05-15T18:33:35.419Z,1589567615.419 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:33:35.419Z,1589567615.419 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:33:36.708Z,1589567616.708 [CommandLine](IMPORTANT): got command configSet AHRS_M2.loadAtStartup 0.000000 bool persist
2020-05-15T18:33:36.709Z,1589567616.709 [CommandLine](IMPORTANT): configSet AHRS_M2.loadAtStartup requires a restart to take effect.
2020-05-15T18:33:43.408Z,1589567623.408 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-05-15T18:33:43.408Z,1589567623.408 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-05-15T18:33:43.408Z,1589567623.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-05-15T18:33:43.419Z,1589567623.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-05-15T18:33:43.821Z,1589567623.821 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-05-15T18:33:43.821Z,1589567623.821 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-05-15T18:33:45.432Z,1589567625.432 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:33:45.435Z,1589567625.435 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:33:45.441Z,1589567625.441 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:33:45.442Z,1589567625.442 [Onboard](IMPORTANT): average counter: 5
2020-05-15T18:33:45.442Z,1589567625.442 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:33:45.442Z,1589567625.442 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:33:45.454Z,1589567625.454 [Onboard](IMPORTANT): Average Battery Current:0.000000 mA
2020-05-15T18:33:48.246Z,1589567628.246 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-05-15T18:33:48.246Z,1589567628.246 [DAT] Communications Fault, FailCount= 2
2020-05-15T18:33:48.246Z,1589567628.246 [DAT](ERROR): Communications Fault
2020-05-15T18:33:48.416Z,1589567628.416 [CBIT](ERROR): Communications Fault in component: DAT
2020-05-15T18:33:48.644Z,1589567628.644 [DAT](INFO): Powering down
2020-05-15T18:33:49.509Z,1589567629.509 [CBIT](INFO): Clearing failed state for component DAT
2020-05-15T18:33:49.509Z,1589567629.509 [DAT] No Fault, FailCount= 2
2020-05-15T18:33:51.885Z,1589567631.885 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-05-15T18:33:51.885Z,1589567631.885 [DAT](INFO): Powering up
2020-05-15T18:33:51.885Z,1589567631.885 [DAT](DEBUG): Initializing DAT.
2020-05-15T18:33:51.935Z,1589567631.935 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-05-15T18:33:55.532Z,1589567635.532 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:33:55.555Z,1589567635.555 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:33:55.561Z,1589567635.561 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:33:55.562Z,1589567635.562 [Onboard](IMPORTANT): average counter: 6
2020-05-15T18:33:55.562Z,1589567635.562 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:33:55.562Z,1589567635.562 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:33:58.347Z,1589567638.347 [Startup:StartupSatComms:A](INFO): Timed out from 2020-05-15T18:32:58.2Z
2020-05-15T18:33:58.347Z,1589567638.347 [Startup:StartupSatComms:A] Stopped
2020-05-15T18:33:58.347Z,1589567638.347 [Startup:StartupSatComms:B] Running Loop=1
2020-05-15T18:33:58.756Z,1589567638.756 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-05-15T18:34:05.619Z,1589567645.619 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:34:05.632Z,1589567645.632 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:34:05.638Z,1589567645.638 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:34:05.638Z,1589567645.638 [Onboard](IMPORTANT): average counter: 0
2020-05-15T18:34:05.638Z,1589567645.638 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:34:05.638Z,1589567645.638 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:34:15.717Z,1589567655.717 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:34:15.721Z,1589567655.721 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:34:15.727Z,1589567655.727 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:34:15.727Z,1589567655.727 [Onboard](IMPORTANT): average counter: 1
2020-05-15T18:34:15.727Z,1589567655.727 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:34:15.728Z,1589567655.728 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:34:25.822Z,1589567665.822 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:34:25.825Z,1589567665.825 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:34:25.831Z,1589567665.831 [Onboard](IMPORTANT): Battery Current:7.815000 mA
2020-05-15T18:34:25.832Z,1589567665.832 [Onboard](IMPORTANT): average counter: 2
2020-05-15T18:34:25.832Z,1589567665.832 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:34:25.832Z,1589567665.832 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:34:35.918Z,1589567675.918 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:34:35.921Z,1589567675.921 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:34:35.927Z,1589567675.927 [Onboard](IMPORTANT): Battery Current:7.815000 mA
2020-05-15T18:34:35.927Z,1589567675.927 [Onboard](IMPORTANT): average counter: 3
2020-05-15T18:34:35.927Z,1589567675.927 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:34:35.927Z,1589567675.927 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:34:46.018Z,1589567686.018 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:34:46.021Z,1589567686.021 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:34:46.027Z,1589567686.027 [Onboard](IMPORTANT): Battery Current:7.294000 mA
2020-05-15T18:34:46.027Z,1589567686.027 [Onboard](IMPORTANT): average counter: 4
2020-05-15T18:34:46.027Z,1589567686.027 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:34:46.028Z,1589567686.028 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:34:46.039Z,1589567686.039 [Onboard](IMPORTANT): Average Battery Current:0.000000 mA
2020-05-15T18:34:49.448Z,1589567689.448 [CommandLine](IMPORTANT): got command report mod platform_average_power
2020-05-15T18:34:49.717Z,1589567689.717 [Reporter](INFO): platform_average_power no_value
2020-05-15T18:34:52.075Z,1589567692.075 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-05-15T18:34:52.075Z,1589567692.075 [DAT] Communications Fault, FailCount= 3
2020-05-15T18:34:52.076Z,1589567692.076 [DAT](ERROR): Communications Fault
2020-05-15T18:34:52.091Z,1589567692.091 [CBIT](ERROR): Communications Fault in component: DAT
2020-05-15T18:34:52.480Z,1589567692.480 [DAT](INFO): Powering down
2020-05-15T18:34:53.317Z,1589567693.317 [CBIT](INFO): Clearing failed state for component DAT
2020-05-15T18:34:53.317Z,1589567693.317 [DAT] No Fault, FailCount= 3
2020-05-15T18:34:55.709Z,1589567695.709 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-05-15T18:34:55.710Z,1589567695.710 [DAT](INFO): Powering up
2020-05-15T18:34:55.710Z,1589567695.710 [DAT](DEBUG): Initializing DAT.
2020-05-15T18:34:55.756Z,1589567695.756 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-05-15T18:34:56.133Z,1589567696.133 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:34:56.136Z,1589567696.136 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:34:56.142Z,1589567696.142 [Onboard](IMPORTANT): Battery Current:7.815000 mA
2020-05-15T18:34:56.143Z,1589567696.143 [Onboard](IMPORTANT): average counter: 5
2020-05-15T18:34:56.143Z,1589567696.143 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:34:56.143Z,1589567696.143 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:34:56.286Z,1589567696.286 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-05-15T18:34:56.286Z,1589567696.286 [CTD_Seabird] No Fault, FailCount= 3
2020-05-15T18:34:56.400Z,1589567696.400 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-05-15T18:34:56.402Z,1589567696.402 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-05-15T18:34:58.573Z,1589567698.573 [Startup:StartupSatComms:B](INFO): Timed out from 2020-05-15T18:33:58.3Z
2020-05-15T18:34:58.573Z,1589567698.573 [Startup:StartupSatComms:B] Stopped
2020-05-15T18:34:58.574Z,1589567698.574 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-05-15T18:34:58.574Z,1589567698.574 [Startup:StartupSatComms] Stopped
2020-05-15T18:34:58.574Z,1589567698.574 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-05-15T18:34:58.575Z,1589567698.575 [Startup](INFO): Completed Startup
2020-05-15T18:34:58.575Z,1589567698.575 [MissionManager](INFO): Startup is completed.
2020-05-15T18:34:58.575Z,1589567698.575 [MissionManager](INFO): Uninitializing Mission Startup
2020-05-15T18:34:58.575Z,1589567698.575 [Startup] Stopped
2020-05-15T18:34:58.576Z,1589567698.576 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-05-15T18:34:58.576Z,1589567698.576 [Startup:A.GoToSurface] Stopped
2020-05-15T18:34:58.576Z,1589567698.576 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-05-15T18:34:58.980Z,1589567698.980 [MissionManager](IMPORTANT): Started mission Default
2020-05-15T18:34:58.980Z,1589567698.980 [Default] Running Loop=1
2020-05-15T18:34:58.980Z,1589567698.980 [Default](DEBUG): Aggregate::initialize Default
2020-05-15T18:34:58.980Z,1589567698.980 [Default:B.GoToSurface] Running Loop=1
2020-05-15T18:34:58.980Z,1589567698.980 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-05-15T18:34:58.980Z,1589567698.980 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-05-15T18:34:58.981Z,1589567698.981 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-05-15T18:34:58.981Z,1589567698.981 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-05-15T18:34:58.981Z,1589567698.981 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-05-15T18:34:58.982Z,1589567698.982 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-05-15T18:34:58.982Z,1589567698.982 [Default:A.Wait] Running Loop=1
2020-05-15T18:34:58.982Z,1589567698.982 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-05-15T18:35:04.086Z,1589567704.086 [CommandLine](IMPORTANT): got command restart application
2020-05-15T18:35:05.092Z,1589567705.092 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:05.092Z,1589567705.092 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:05.203Z,1589567705.203 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-05-15T18:35:05.204Z,1589567705.204 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:05.204Z,1589567705.204 [CommandLine](INFO): Join timeout helper Thread ID is 3573
2020-05-15T18:35:05.215Z,1589567705.215 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-05-15T18:35:05.216Z,1589567705.216 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:05.216Z,1589567705.216 [NavChartDb](INFO): Join timeout helper Thread ID is 3574
2020-05-15T18:35:05.220Z,1589567705.220 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:05.220Z,1589567705.220 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:05.231Z,1589567705.231 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-05-15T18:35:05.232Z,1589567705.232 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:05.232Z,1589567705.232 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3575
2020-05-15T18:35:05.448Z,1589567705.448 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:05.448Z,1589567705.448 [WetLabsBB2FL](INFO): Powering down
2020-05-15T18:35:05.449Z,1589567705.449 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:05.456Z,1589567705.456 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-05-15T18:35:05.456Z,1589567705.456 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:05.456Z,1589567705.456 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3576
2020-05-15T18:35:06.214Z,1589567706.214 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V
2020-05-15T18:35:06.218Z,1589567706.218 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V
2020-05-15T18:35:06.224Z,1589567706.224 [Onboard](IMPORTANT): Battery Current:7.815000 mA
2020-05-15T18:35:06.224Z,1589567706.224 [Onboard](IMPORTANT): average counter: 6
2020-05-15T18:35:06.224Z,1589567706.224 [Onboard](IMPORTANT): average scaler: 7
2020-05-15T18:35:06.224Z,1589567706.224 [Onboard](IMPORTANT): using main battery for universal
2020-05-15T18:35:06.440Z,1589567706.440 [CTD_Seabird](INFO): Powering down
2020-05-15T18:35:06.572Z,1589567706.572 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:06.572Z,1589567706.572 [CTD_Seabird](INFO): Powering down
2020-05-15T18:35:06.584Z,1589567706.584 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:06.605Z,1589567706.605 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-05-15T18:35:06.605Z,1589567706.605 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:06.606Z,1589567706.606 [Radio_Surface](INFO): Join timeout helper Thread ID is 3577
2020-05-15T18:35:06.935Z,1589567706.935 [Radio_Surface](INFO): Powering down
2020-05-15T18:35:06.936Z,1589567706.936 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:06.937Z,1589567706.937 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:06.948Z,1589567706.948 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-05-15T18:35:06.948Z,1589567706.948 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:06.949Z,1589567706.949 [DataOverHttps](INFO): Join timeout helper Thread ID is 3578
2020-05-15T18:35:07.568Z,1589567707.568 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:07.571Z,1589567707.571 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:07.572Z,1589567707.572 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-05-15T18:35:07.572Z,1589567707.572 [logger ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:07.573Z,1589567707.573 [logger](INFO): Join timeout helper Thread ID is 3579
2020-05-15T18:35:07.584Z,1589567707.584 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:07.584Z,1589567707.584 [logger ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:07.592Z,1589567707.592 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-05-15T18:35:07.592Z,1589567707.592 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:07.592Z,1589567707.592 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-05-15T18:35:07.592Z,1589567707.592 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:07.593Z,1589567707.593 [controlThread](INFO): Join timeout helper Thread ID is 3580
2020-05-15T18:35:07.812Z,1589567707.812 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-15T18:35:07.812Z,1589567707.812 [controlThread](DEBUG): Uninitializing ControlThread
2020-05-15T18:35:07.812Z,1589567707.812 [AHRS_M2](INFO): Powering down
2020-05-15T18:35:07.813Z,1589567707.813 [DVL_micro](INFO): Powering down
2020-05-15T18:35:07.814Z,1589567707.814 [NAL9602](INFO): Powering down
2020-05-15T18:35:07.815Z,1589567707.815 [DAT](INFO): Powering down
2020-05-15T18:35:07.957Z,1589567707.957 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-05-15T18:35:07.958Z,1589567707.958 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-05-15T18:35:07.958Z,1589567707.958 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-05-15T18:35:07.959Z,1589567707.959 [MissionManager](INFO): Uninitializing Mission Default
2020-05-15T18:35:07.959Z,1589567707.959 [Default] Stopped
2020-05-15T18:35:07.959Z,1589567707.959 [Default](DEBUG): Aggregate::uninitialize Default
2020-05-15T18:35:07.959Z,1589567707.959 [Default:A.Wait] Stopped
2020-05-15T18:35:07.959Z,1589567707.959 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-05-15T18:35:07.959Z,1589567707.959 [Default:B.GoToSurface] Stopped
2020-05-15T18:35:07.959Z,1589567707.959 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-05-15T18:35:07.962Z,1589567707.962 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-05-15T18:35:07.962Z,1589567707.962 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-05-15T18:35:07.962Z,1589567707.962 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-05-15T18:35:07.962Z,1589567707.962 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-05-15T18:35:07.963Z,1589567707.963 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-05-15T18:35:07.963Z,1589567707.963 [BuoyancyServo](INFO): Powering down
2020-05-15T18:35:07.976Z,1589567707.976 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-05-15T18:35:07.976Z,1589567707.976 [ElevatorServo](INFO): Powering down
2020-05-15T18:35:07.977Z,1589567707.977 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-05-15T18:35:07.977Z,1589567707.977 [MassServo](INFO): Powering down
2020-05-15T18:35:07.977Z,1589567707.977 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-05-15T18:35:07.977Z,1589567707.977 [RudderServo](INFO): Powering down
2020-05-15T18:35:07.978Z,1589567707.978 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-05-15T18:35:07.978Z,1589567707.978 [ThrusterServo](INFO): Powering down
2020-05-15T18:35:07.979Z,1589567707.979 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-05-15T18:35:07.980Z,1589567707.980 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-05-15T18:35:07.980Z,1589567707.980 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-05-15T18:35:07.980Z,1589567707.980 [CBIT](DEBUG): Powering off loads.
2020-05-15T18:35:07.991Z,1589567707.991 [CBIT](DEBUG): Disabling WDT.
2020-05-15T18:35:08.003Z,1589567708.003 [CBIT](DEBUG): Opening all GF detection circuits.
2020-05-15T18:35:08.004Z,1589567708.004 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:08.080Z,1589567708.080 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:08.088Z,1589567708.088 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:08.143Z,1589567708.143 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:08.148Z,1589567708.148 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:08.172Z,1589567708.172 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-05-15T18:35:08.231Z,1589567708.231 [logger ThreadHandler](INFO): Thread cancelled.