2020-05-28T15:38:34.359Z,1590680314.359 [Supervisor](DEBUG): Initializing supervisor. 2020-05-28T15:38:34.362Z,1590680314.362 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-05-28T15:38:34.363Z,1590680314.363 [SyncHandler](INFO): Protected caller Thread ID is 5076 2020-05-28T15:38:34.363Z,1590680314.363 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-05-28T15:38:34.364Z,1590680314.364 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-05-28T15:38:34.365Z,1590680314.365 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5077 2020-05-28T15:38:34.368Z,1590680314.368 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-05-28T15:38:34.445Z,1590680314.445 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-05-28T15:38:34.447Z,1590680314.447 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-05-28T15:38:34.447Z,1590680314.447 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5078 2020-05-28T15:38:34.448Z,1590680314.448 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-05-28T15:38:34.449Z,1590680314.449 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-05-28T15:38:34.449Z,1590680314.449 [logger ThreadHandler](INFO): Protected caller Thread ID is 5079 2020-05-28T15:38:34.452Z,1590680314.452 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-05-28T15:38:34.452Z,1590680314.452 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-05-28T15:38:34.454Z,1590680314.454 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-05-28T15:38:34.819Z,1590680314.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-05-28T15:38:34.820Z,1590680314.820 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-05-28T15:38:35.568Z,1590680315.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-05-28T15:38:35.569Z,1590680315.569 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-05-28T15:38:35.730Z,1590680315.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-05-28T15:38:35.731Z,1590680315.731 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-05-28T15:38:36.330Z,1590680316.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-05-28T15:38:36.331Z,1590680316.331 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-05-28T15:38:36.436Z,1590680316.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-05-28T15:38:36.436Z,1590680316.436 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-05-28T15:38:36.520Z,1590680316.520 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-05-28T15:38:36.888Z,1590680316.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-05-28T15:38:36.889Z,1590680316.889 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-05-28T15:38:37.056Z,1590680317.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-05-28T15:38:37.057Z,1590680317.057 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-05-28T15:38:37.171Z,1590680317.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-05-28T15:38:37.171Z,1590680317.171 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-05-28T15:38:37.410Z,1590680317.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-05-28T15:38:37.410Z,1590680317.410 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-05-28T15:38:38.048Z,1590680318.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-05-28T15:38:38.049Z,1590680318.049 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-05-28T15:38:38.599Z,1590680318.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-05-28T15:38:38.599Z,1590680318.599 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-05-28T15:38:38.713Z,1590680318.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-05-28T15:38:38.713Z,1590680318.713 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-05-28T15:38:38.928Z,1590680318.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-05-28T15:38:38.928Z,1590680318.928 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-05-28T15:38:39.310Z,1590680319.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-05-28T15:38:39.311Z,1590680319.311 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-05-28T15:38:39.594Z,1590680319.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-05-28T15:38:39.596Z,1590680319.596 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2020-05-28T15:38:39.597Z,1590680319.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2020-05-28T15:38:39.700Z,1590680319.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2020-05-28T15:38:39.855Z,1590680319.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2020-05-28T15:38:39.944Z,1590680319.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2020-05-28T15:38:40.031Z,1590680320.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2020-05-28T15:38:40.145Z,1590680320.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2020-05-28T15:38:40.352Z,1590680320.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2020-05-28T15:38:40.658Z,1590680320.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-05-28T15:38:40.659Z,1590680320.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2020-05-28T15:38:40.778Z,1590680320.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2020-05-28T15:38:40.878Z,1590680320.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2020-05-28T15:38:40.983Z,1590680320.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2020-05-28T15:38:41.082Z,1590680321.082 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/LOGIN/ 2020-05-28T15:38:41.083Z,1590680321.083 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-05-28T15:38:41.091Z,1590680321.091 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-05-28T15:38:41.171Z,1590680321.171 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-05-28T15:38:41.171Z,1590680321.171 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-05-28T15:38:41.190Z,1590680321.190 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-05-28T15:38:41.191Z,1590680321.191 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-05-28T15:38:41.234Z,1590680321.234 [DepthRateCalculator] Loaded 2020-05-28T15:38:41.235Z,1590680321.235 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-05-28T15:38:41.242Z,1590680321.242 [PitchRateCalculator] Loaded 2020-05-28T15:38:41.242Z,1590680321.242 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-05-28T15:38:41.261Z,1590680321.261 [SpeedCalculator] Loaded 2020-05-28T15:38:41.261Z,1590680321.261 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-05-28T15:38:41.288Z,1590680321.288 [TempGradientCalculator] Loaded 2020-05-28T15:38:41.289Z,1590680321.289 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-05-28T15:38:41.296Z,1590680321.296 [YawRateCalculator] Loaded 2020-05-28T15:38:41.296Z,1590680321.296 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-05-28T15:38:41.346Z,1590680321.346 [ElevatorOffsetCalculator] Loaded 2020-05-28T15:38:41.347Z,1590680321.347 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-05-28T15:38:41.347Z,1590680321.347 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-05-28T15:38:41.348Z,1590680321.348 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-05-28T15:38:41.397Z,1590680321.397 [VerticalControl](DEBUG): Construct VerticalControl. 2020-05-28T15:38:41.519Z,1590680321.519 [VerticalControl] Loaded 2020-05-28T15:38:41.520Z,1590680321.520 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-05-28T15:38:41.521Z,1590680321.521 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-05-28T15:38:41.622Z,1590680321.622 [HorizontalControl] Loaded 2020-05-28T15:38:41.623Z,1590680321.623 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-05-28T15:38:41.624Z,1590680321.624 [SpeedControl](DEBUG): Construct SpeedControl. 2020-05-28T15:38:41.630Z,1590680321.630 [SpeedControl] Loaded 2020-05-28T15:38:41.630Z,1590680321.630 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-05-28T15:38:41.631Z,1590680321.631 [LoopControl](DEBUG): Construct LoopControl. 2020-05-28T15:38:41.632Z,1590680321.632 [LoopControl] Loaded 2020-05-28T15:38:41.632Z,1590680321.632 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-05-28T15:38:41.632Z,1590680321.632 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-05-28T15:38:41.633Z,1590680321.633 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-05-28T15:38:41.771Z,1590680321.771 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-05-28T15:38:41.772Z,1590680321.772 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-05-28T15:38:41.797Z,1590680321.797 [NavChart] Loaded 2020-05-28T15:38:41.798Z,1590680321.798 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-05-28T15:38:41.803Z,1590680321.803 [UniversalFixResidualReporter] Loaded 2020-05-28T15:38:41.803Z,1590680321.803 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-05-28T15:38:41.804Z,1590680321.804 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-05-28T15:38:41.804Z,1590680321.804 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-05-28T15:38:41.907Z,1590680321.907 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-05-28T15:38:41.908Z,1590680321.908 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-05-28T15:38:42.307Z,1590680322.307 [DataOverHttps] Loaded 2020-05-28T15:38:42.307Z,1590680322.307 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-05-28T15:38:42.308Z,1590680322.308 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0 2020-05-28T15:38:42.309Z,1590680322.309 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5166 2020-05-28T15:38:42.327Z,1590680322.327 [Depth_Keller] Loaded 2020-05-28T15:38:42.327Z,1590680322.327 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-05-28T15:38:42.333Z,1590680322.333 [DropWeight] Loaded 2020-05-28T15:38:42.333Z,1590680322.333 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-05-28T15:38:42.453Z,1590680322.453 [NAL9602] Loaded 2020-05-28T15:38:42.454Z,1590680322.454 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-05-28T15:38:42.502Z,1590680322.502 [Onboard] Loaded 2020-05-28T15:38:42.502Z,1590680322.502 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-05-28T15:38:42.506Z,1590680322.506 [Radio_Surface] Loaded 2020-05-28T15:38:42.507Z,1590680322.507 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-05-28T15:38:42.508Z,1590680322.508 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0 2020-05-28T15:38:42.508Z,1590680322.508 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5167 2020-05-28T15:38:42.683Z,1590680322.683 [DAT] Loaded 2020-05-28T15:38:42.683Z,1590680322.683 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-05-28T15:38:44.772Z,1590680324.772 [BPC1] Loaded 2020-05-28T15:38:44.773Z,1590680324.773 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-05-28T15:38:44.773Z,1590680324.773 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-05-28T15:38:44.774Z,1590680324.774 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-05-28T15:38:45.012Z,1590680325.012 [BuoyancyServo] Loaded 2020-05-28T15:38:45.012Z,1590680325.012 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-05-28T15:38:45.037Z,1590680325.037 [ElevatorServo] Loaded 2020-05-28T15:38:45.037Z,1590680325.037 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-05-28T15:38:45.051Z,1590680325.051 [MassServo] Loaded 2020-05-28T15:38:45.052Z,1590680325.052 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-05-28T15:38:45.083Z,1590680325.083 [RudderServo] Loaded 2020-05-28T15:38:45.083Z,1590680325.083 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-05-28T15:38:45.138Z,1590680325.138 [ThrusterServo] Loaded 2020-05-28T15:38:45.139Z,1590680325.139 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-05-28T15:38:45.139Z,1590680325.139 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-05-28T15:38:45.140Z,1590680325.140 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-05-28T15:38:45.237Z,1590680325.237 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-05-28T15:38:45.238Z,1590680325.238 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-05-28T15:38:45.404Z,1590680325.404 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-05-28T15:38:45.404Z,1590680325.404 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-05-28T15:38:45.699Z,1590680325.699 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-05-28T15:38:45.699Z,1590680325.699 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-05-28T15:38:45.923Z,1590680325.923 [ESPComponent] Loaded 2020-05-28T15:38:45.924Z,1590680325.924 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-05-28T15:38:45.942Z,1590680325.942 [PAR_Licor] Loaded 2020-05-28T15:38:45.943Z,1590680325.943 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-05-28T15:38:45.952Z,1590680325.952 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-05-28T15:38:45.952Z,1590680325.952 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-05-28T15:38:45.958Z,1590680325.958 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-05-28T15:38:45.958Z,1590680325.958 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-05-28T15:38:45.963Z,1590680325.963 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-05-28T15:38:45.963Z,1590680325.963 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-05-28T15:38:45.969Z,1590680325.969 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-05-28T15:38:45.969Z,1590680325.969 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-05-28T15:38:45.974Z,1590680325.974 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-05-28T15:38:45.974Z,1590680325.974 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-05-28T15:38:45.979Z,1590680325.979 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-05-28T15:38:45.979Z,1590680325.979 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-05-28T15:38:45.984Z,1590680325.984 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-05-28T15:38:45.984Z,1590680325.984 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-05-28T15:38:45.989Z,1590680325.989 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:45.995Z,1590680325.995 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:45.995Z,1590680325.995 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:45.995Z,1590680325.995 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:46.000Z,1590680326.000 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:46.000Z,1590680326.000 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:46.005Z,1590680326.005 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:46.006Z,1590680326.006 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-28T15:38:46.011Z,1590680326.011 [WetLabsBB2FL] Loaded 2020-05-28T15:38:46.011Z,1590680326.011 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-05-28T15:38:46.012Z,1590680326.012 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD34E0 2020-05-28T15:38:46.013Z,1590680326.013 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5168 2020-05-28T15:38:46.014Z,1590680326.014 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-05-28T15:38:46.014Z,1590680326.014 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-05-28T15:38:46.154Z,1590680326.154 [SBIT](DEBUG): Construct Startup Built In Test. 2020-05-28T15:38:46.168Z,1590680326.168 [SBIT] Loaded 2020-05-28T15:38:46.168Z,1590680326.168 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-05-28T15:38:46.169Z,1590680326.169 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-05-28T15:38:46.188Z,1590680326.188 [IBIT] Loaded 2020-05-28T15:38:46.188Z,1590680326.188 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-05-28T15:38:46.192Z,1590680326.192 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-05-28T15:38:46.378Z,1590680326.378 [CBIT] Loaded 2020-05-28T15:38:46.378Z,1590680326.378 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-05-28T15:38:46.379Z,1590680326.379 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-05-28T15:38:46.384Z,1590680326.384 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-05-28T15:38:46.385Z,1590680326.385 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-05-28T15:38:46.395Z,1590680326.395 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-05-28T15:38:46.396Z,1590680326.396 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C4F4E0 2020-05-28T15:38:46.396Z,1590680326.396 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5169 2020-05-28T15:38:46.402Z,1590680326.402 [Supervisor](INFO): Main Thread ID is 5075 2020-05-28T15:38:46.403Z,1590680326.403 [Supervisor](DEBUG): Running supervisor. 2020-05-28T15:38:46.403Z,1590680326.403 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5170 2020-05-28T15:38:46.405Z,1590680326.405 [controlThread ThreadHandler](INFO): Handler Thread ID is 5171 2020-05-28T15:38:46.406Z,1590680326.406 [controlThread](DEBUG): Initializing ControlThread 2020-05-28T15:38:46.407Z,1590680326.407 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-05-28T15:38:46.407Z,1590680326.407 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-05-28T15:38:46.408Z,1590680326.408 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-05-28T15:38:46.408Z,1590680326.408 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-05-28T15:38:46.409Z,1590680326.409 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-05-28T15:38:46.409Z,1590680326.409 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-05-28T15:38:46.410Z,1590680326.410 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-05-28T15:38:46.412Z,1590680326.412 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-05-28T15:38:46.413Z,1590680326.413 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-05-28T15:38:46.413Z,1590680326.413 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-05-28T15:38:46.414Z,1590680326.414 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-05-28T15:38:46.415Z,1590680326.415 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-05-28T15:38:46.428Z,1590680326.428 [logger ThreadHandler](INFO): Handler Thread ID is 5172 2020-05-28T15:38:46.444Z,1590680326.444 [SBIT](INFO): Initialize SBIT Component. 2020-05-28T15:38:46.445Z,1590680326.445 [SBIT](IMPORTANT): git: 2020-05-06_C-4-ge907442 2020-05-28T15:38:46.445Z,1590680326.445 [SBIT](INFO): git hash: e907442e8d324ef9e313fad2e4afa1f9562153e8 2020-05-28T15:38:46.445Z,1590680326.445 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-05-28T15:38:46.445Z,1590680326.445 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 2020-05-28T15:38:46.446Z,1590680326.446 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2020-05-28T15:38:46.447Z,1590680326.447 [IBIT](INFO): Initialize IBIT Component. 2020-05-28T15:38:46.452Z,1590680326.452 [CBIT](DEBUG): Initialize CBIT Component. 2020-05-28T15:38:46.453Z,1590680326.453 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5173 2020-05-28T15:38:46.454Z,1590680326.454 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-05-28T15:38:46.463Z,1590680326.463 [CBIT](DEBUG): Initialized mux pins. 2020-05-28T15:38:46.464Z,1590680326.464 [CBIT](DEBUG): Initializing the watchdog timer. 2020-05-28T15:38:46.464Z,1590680326.464 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5174 2020-05-28T15:38:46.472Z,1590680326.472 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5175 2020-05-28T15:38:46.473Z,1590680326.473 [WetLabsBB2FL](INFO): Powering down 2020-05-28T15:38:46.491Z,1590680326.491 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2020-05-28T15:38:46.492Z,1590680326.492 [CBIT] Hardware Fault, FailCount= 1 2020-05-28T15:38:46.492Z,1590680326.492 [CBIT](ERROR): Hardware Fault 2020-05-28T15:38:46.492Z,1590680326.492 [CBIT](DEBUG): Initializing heartbeat. 2020-05-28T15:38:46.500Z,1590680326.500 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5176 2020-05-28T15:38:46.504Z,1590680326.504 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-05-28T15:38:46.504Z,1590680326.504 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-05-28T15:38:46.504Z,1590680326.504 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-05-28T15:38:46.504Z,1590680326.504 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-05-28T15:38:46.505Z,1590680326.505 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-05-28T15:38:46.505Z,1590680326.505 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-05-28T15:38:46.505Z,1590680326.505 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-05-28T15:38:46.505Z,1590680326.505 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-05-28T15:38:46.505Z,1590680326.505 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-05-28T15:38:46.506Z,1590680326.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-05-28T15:38:46.506Z,1590680326.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-05-28T15:38:46.506Z,1590680326.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-05-28T15:38:46.506Z,1590680326.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-05-28T15:38:46.506Z,1590680326.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-05-28T15:38:46.507Z,1590680326.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-05-28T15:38:46.507Z,1590680326.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-05-28T15:38:46.563Z,1590680326.563 [CBIT](DEBUG): Deactivating GF circuits. 2020-05-28T15:38:46.564Z,1590680326.564 [CBIT](DEBUG): Deactivating emergency mode. 2020-05-28T15:38:46.599Z,1590680326.599 [CBIT](DEBUG): Backplane powered. 2020-05-28T15:38:46.601Z,1590680326.601 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-05-28T15:38:46.611Z,1590680326.611 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-28T15:38:46.633Z,1590680326.633 [MissionManager](DEBUG): 2020-05-28T15:38:46.634Z,1590680326.634 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-05-28T15:38:46.703Z,1590680326.703 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-05-28T15:38:46.720Z,1590680326.720 [Default:A.Wait](DEBUG): Construct Wait. 2020-05-28T15:38:46.722Z,1590680326.722 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-28T15:38:46.772Z,1590680326.772 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-05-28T15:38:46.775Z,1590680326.775 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-05-28T15:38:46.802Z,1590680326.802 [Default:E.Execute](DEBUG): Construct Execute. 2020-05-28T15:38:46.812Z,1590680326.812 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-05-28T15:38:46.828Z,1590680326.828 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-05-28T15:38:46.868Z,1590680326.868 [Radio_Surface](INFO): Powering up 2020-05-28T15:38:46.879Z,1590680326.879 [DAT](INFO): Start 2020-05-28T15:38:46.881Z,1590680326.881 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-05-28T15:38:46.881Z,1590680326.881 [DAT](INFO): Powering up 2020-05-28T15:38:46.882Z,1590680326.882 [DAT](DEBUG): Initializing DAT. 2020-05-28T15:38:46.904Z,1590680326.904 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-05-28T15:38:46.976Z,1590680326.976 [WetLabsBB2FL](FAULT): LCB fault: Software Overcurrent. 2020-05-28T15:38:46.976Z,1590680326.976 [WetLabsBB2FL] Hardware Fault, FailCount= 1 2020-05-28T15:38:46.976Z,1590680326.976 [WetLabsBB2FL](ERROR): Hardware Fault 2020-05-28T15:38:47.022Z,1590680327.022 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-05-28T15:38:47.032Z,1590680327.032 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-05-28T15:38:47.033Z,1590680327.033 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-05-28T15:38:47.040Z,1590680327.040 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-05-28T15:38:47.041Z,1590680327.041 [MassServo](DEBUG): Initializing EZServoServo. 2020-05-28T15:38:47.048Z,1590680327.048 [MassServo](DEBUG): Initializing MassServo. 2020-05-28T15:38:47.049Z,1590680327.049 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-28T15:38:47.056Z,1590680327.056 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-28T15:38:47.057Z,1590680327.057 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-05-28T15:38:47.064Z,1590680327.064 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-05-28T15:38:47.066Z,1590680327.066 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-05-28T15:38:47.066Z,1590680327.066 [CBIT](DEBUG): Powering off loads. 2020-05-28T15:38:47.079Z,1590680327.079 [CBIT](DEBUG): Disabling WDT. 2020-05-28T15:38:47.091Z,1590680327.091 [CBIT](DEBUG): Opening all GF detection circuits. 2020-05-28T15:38:47.294Z,1590680327.294 [DAT](INFO): Starting 2020-05-28T15:38:47.659Z,1590680327.659 [DAT](INFO): Starting 2020-05-28T15:38:48.032Z,1590680328.032 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-05-28T15:38:48.032Z,1590680328.032 [RudderServo](FAULT): Rudder failed to initialize 2020-05-28T15:38:48.032Z,1590680328.032 [RudderServo] Communications Fault, FailCount= 1 2020-05-28T15:38:48.032Z,1590680328.032 [RudderServo](ERROR): Communications Fault 2020-05-28T15:38:48.355Z,1590680328.355 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2020-05-28T15:38:48.679Z,1590680328.679 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2020-05-28T15:38:48.680Z,1590680328.680 [ThrusterServo](FAULT): Thruster failed to initialize 2020-05-28T15:38:48.680Z,1590680328.680 [ThrusterServo] Communications Fault, FailCount= 1 2020-05-28T15:38:48.680Z,1590680328.680 [ThrusterServo](ERROR): Communications Fault 2020-05-28T15:38:48.704Z,1590680328.704 [DAT](INFO): Starting 2020-05-28T15:38:49.091Z,1590680329.091 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2020-05-28T15:38:49.092Z,1590680329.092 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2020-05-28T15:38:49.092Z,1590680329.092 [BuoyancyServo] Communications Fault, FailCount= 1 2020-05-28T15:38:49.092Z,1590680329.092 [BuoyancyServo](ERROR): Communications Fault 2020-05-28T15:38:49.403Z,1590680329.403 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2020-05-28T15:38:49.404Z,1590680329.404 [ElevatorServo](FAULT): Elevator failed to initialize 2020-05-28T15:38:49.404Z,1590680329.404 [ElevatorServo] Communications Fault, FailCount= 1 2020-05-28T15:38:49.404Z,1590680329.404 [ElevatorServo](ERROR): Communications Fault 2020-05-28T15:38:49.405Z,1590680329.405 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-05-28T15:38:49.405Z,1590680329.405 [RudderServo](INFO): Powering down 2020-05-28T15:38:49.504Z,1590680329.504 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-05-28T15:38:49.504Z,1590680329.504 [ThrusterServo](INFO): Powering down 2020-05-28T15:38:49.604Z,1590680329.604 [DAT](INFO): Starting 2020-05-28T15:38:49.633Z,1590680329.633 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-05-28T15:38:49.634Z,1590680329.634 [ElevatorServo](INFO): Powering down 2020-05-28T15:38:49.716Z,1590680329.716 [RudderServo](FAULT): LCB fault: Current Limiter Activated. 2020-05-28T15:38:49.716Z,1590680329.716 [RudderServo] Hardware Fault, FailCount= 1 2020-05-28T15:38:49.716Z,1590680329.716 [RudderServo](ERROR): Hardware Fault 2020-05-28T15:38:49.716Z,1590680329.716 [ThrusterServo](FAULT): LCB fault: Current Limiter Activated. 2020-05-28T15:38:49.717Z,1590680329.717 [ThrusterServo] Hardware Fault, FailCount= 1 2020-05-28T15:38:49.717Z,1590680329.717 [ThrusterServo](ERROR): Hardware Fault 2020-05-28T15:38:49.996Z,1590680329.996 [DAT](INFO): Starting 2020-05-28T15:38:50.067Z,1590680330.067 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-28T15:38:50.188Z,1590680330.188 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-28T15:38:50.399Z,1590680330.399 [DAT](INFO): Starting 2020-05-28T15:38:50.482Z,1590680330.482 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-05-28T15:38:50.604Z,1590680330.604 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-05-28T15:38:50.907Z,1590680330.907 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2020-05-28T15:38:50.908Z,1590680330.908 [MassServo] Communications Fault, FailCount= 1 2020-05-28T15:38:50.908Z,1590680330.908 [MassServo](ERROR): Communications Fault 2020-05-28T15:38:50.932Z,1590680330.932 [DAT](INFO): Starting 2020-05-28T15:38:50.992Z,1590680330.992 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-05-28T15:38:50.992Z,1590680330.992 [MassServo](INFO): Powering down 2020-05-28T15:38:51.335Z,1590680331.335 [DAT](INFO): Starting 2020-05-28T15:38:51.380Z,1590680331.380 [MassServo](FAULT): LCB fault: Current Limiter Activated. 2020-05-28T15:38:51.380Z,1590680331.380 [MassServo] Hardware Fault, FailCount= 1 2020-05-28T15:38:51.381Z,1590680331.381 [MassServo](ERROR): Hardware Fault 2020-05-28T15:38:51.739Z,1590680331.739 [DAT](INFO): Starting 2020-05-28T15:38:52.143Z,1590680332.143 [DAT](INFO): Starting 2020-05-28T15:38:52.548Z,1590680332.548 [DAT](INFO): Starting 2020-05-28T15:38:52.951Z,1590680332.951 [DAT](INFO): Starting 2020-05-28T15:38:53.368Z,1590680333.368 [DAT](INFO): Starting 2020-05-28T15:38:53.760Z,1590680333.760 [DAT](INFO): Starting 2020-05-28T15:38:54.164Z,1590680334.164 [DAT](INFO): Starting 2020-05-28T15:38:54.579Z,1590680334.579 [DAT](INFO): Starting 2020-05-28T15:38:54.974Z,1590680334.974 [DAT](INFO): Starting 2020-05-28T15:38:55.375Z,1590680335.375 [DAT](INFO): Starting 2020-05-28T15:38:55.780Z,1590680335.780 [DAT](INFO): Starting 2020-05-28T15:38:56.183Z,1590680336.183 [DAT](INFO): Starting 2020-05-28T15:38:56.601Z,1590680336.601 [DAT](INFO): Starting 2020-05-28T15:38:56.991Z,1590680336.991 [DAT](INFO): Starting 2020-05-28T15:38:57.395Z,1590680337.395 [DAT](INFO): Starting 2020-05-28T15:38:57.799Z,1590680337.799 [DAT](INFO): Starting 2020-05-28T15:38:58.204Z,1590680338.204 [DAT](INFO): Starting 2020-05-28T15:38:58.608Z,1590680338.608 [DAT](INFO): Starting 2020-05-28T15:38:59.012Z,1590680339.012 [DAT](INFO): Starting 2020-05-28T15:38:59.415Z,1590680339.415 [DAT](INFO): Starting 2020-05-28T15:38:59.820Z,1590680339.820 [DAT](INFO): Starting 2020-05-28T15:39:00.223Z,1590680340.223 [DAT](INFO): Starting 2020-05-28T15:39:00.628Z,1590680340.628 [DAT](INFO): Starting 2020-05-28T15:39:01.032Z,1590680341.032 [DAT](INFO): Starting 2020-05-28T15:39:01.443Z,1590680341.443 [DAT](INFO): Starting 2020-05-28T15:39:01.839Z,1590680341.839 [DAT](INFO): Starting 2020-05-28T15:39:02.244Z,1590680342.244 [DAT](INFO): Starting 2020-05-28T15:39:02.647Z,1590680342.647 [DAT](INFO): Starting 2020-05-28T15:39:03.052Z,1590680343.052 [DAT](INFO): Starting 2020-05-28T15:39:03.456Z,1590680343.456 [DAT](INFO): Starting 2020-05-28T15:39:03.860Z,1590680343.860 [DAT](INFO): Starting 2020-05-28T15:39:04.263Z,1590680344.263 [DAT](INFO): Starting 2020-05-28T15:39:04.668Z,1590680344.668 [DAT](INFO): Starting 2020-05-28T15:39:05.071Z,1590680345.071 [DAT](INFO): Starting 2020-05-28T15:39:05.483Z,1590680345.483 [DAT](INFO): Starting 2020-05-28T15:39:05.879Z,1590680345.879 [DAT](INFO): Starting 2020-05-28T15:39:06.284Z,1590680346.284 [DAT](INFO): Starting 2020-05-28T15:39:06.699Z,1590680346.699 [DAT](INFO): Starting 2020-05-28T15:39:07.092Z,1590680347.092 [DAT](INFO): Starting 2020-05-28T15:39:07.133Z,1590680347.133 [SBIT](IMPORTANT): Beginning Startup BIT 2020-05-28T15:39:07.495Z,1590680347.495 [DAT](INFO): Starting 2020-05-28T15:39:07.900Z,1590680347.900 [DAT](INFO): Starting 2020-05-28T15:39:08.304Z,1590680348.304 [DAT](INFO): Starting 2020-05-28T15:39:08.708Z,1590680348.708 [DAT](INFO): Starting 2020-05-28T15:39:09.111Z,1590680349.111 [DAT](INFO): Starting 2020-05-28T15:39:09.514Z,1590680349.514 [DAT](INFO): Starting 2020-05-28T15:39:09.916Z,1590680349.916 [DAT](INFO): Starting 2020-05-28T15:39:10.323Z,1590680350.323 [DAT](INFO): Starting 2020-05-28T15:39:10.728Z,1590680350.728 [DAT](INFO): Starting 2020-05-28T15:39:11.129Z,1590680351.129 [DAT](INFO): Starting 2020-05-28T15:39:11.533Z,1590680351.533 [DAT](INFO): Starting 2020-05-28T15:39:11.938Z,1590680351.938 [DAT](INFO): Starting 2020-05-28T15:39:12.344Z,1590680352.344 [DAT](INFO): Starting 2020-05-28T15:39:12.731Z,1590680352.731 [NAL9602](INFO): Powering up NAL9602 2020-05-28T15:39:12.747Z,1590680352.747 [DAT](INFO): Starting 2020-05-28T15:39:13.149Z,1590680353.149 [DAT](INFO): Starting 2020-05-28T15:39:13.556Z,1590680353.556 [DAT](INFO): Starting 2020-05-28T15:39:13.960Z,1590680353.960 [DAT](INFO): Starting 2020-05-28T15:39:14.366Z,1590680354.366 [DAT](INFO): Starting 2020-05-28T15:39:14.765Z,1590680354.765 [DAT](INFO): Starting 2020-05-28T15:39:15.171Z,1590680355.171 [DAT](INFO): Starting 2020-05-28T15:39:15.576Z,1590680355.576 [DAT](INFO): Starting 2020-05-28T15:39:15.984Z,1590680355.984 [DAT](INFO): Starting 2020-05-28T15:39:16.381Z,1590680356.381 [DAT](INFO): Starting 2020-05-28T15:39:16.797Z,1590680356.797 [DAT](INFO): Starting 2020-05-28T15:39:17.189Z,1590680357.189 [DAT](INFO): Starting 2020-05-28T15:39:17.599Z,1590680357.599 [DAT](INFO): Starting 2020-05-28T15:39:17.996Z,1590680357.996 [DAT](INFO): Starting 2020-05-28T15:39:18.401Z,1590680358.401 [DAT](INFO): Starting 2020-05-28T15:39:18.807Z,1590680358.807 [DAT](INFO): Starting 2020-05-28T15:39:19.213Z,1590680359.213 [DAT](INFO): Starting 2020-05-28T15:39:19.618Z,1590680359.618 [DAT](INFO): Starting 2020-05-28T15:39:20.019Z,1590680360.019 [DAT](INFO): Starting 2020-05-28T15:39:20.421Z,1590680360.421 [DAT](INFO): Starting 2020-05-28T15:39:20.442Z,1590680360.442 [SBIT](ERROR): Could not read elevatorAngleReader_. 2020-05-28T15:39:20.442Z,1590680360.442 [SBIT](ERROR): Could not read rudderAngleReader_. 2020-05-28T15:39:20.442Z,1590680360.442 [SBIT](ERROR): Could not read massPosReader_. 2020-05-28T15:39:20.828Z,1590680360.828 [DAT](INFO): Starting 2020-05-28T15:39:21.232Z,1590680361.232 [DAT](INFO): Starting 2020-05-28T15:39:21.634Z,1590680361.634 [DAT](INFO): Starting 2020-05-28T15:39:22.037Z,1590680362.037 [DAT](INFO): Starting 2020-05-28T15:39:22.442Z,1590680362.442 [DAT](INFO): Starting 2020-05-28T15:39:22.851Z,1590680362.851 [DAT](INFO): Starting 2020-05-28T15:39:23.268Z,1590680363.268 [DAT](INFO): Starting 2020-05-28T15:39:23.654Z,1590680363.654 [DAT](INFO): Starting 2020-05-28T15:39:24.061Z,1590680364.061 [DAT](INFO): Starting 2020-05-28T15:39:24.477Z,1590680364.477 [DAT](INFO): Starting 2020-05-28T15:39:24.871Z,1590680364.871 [DAT](INFO): Starting 2020-05-28T15:39:25.272Z,1590680365.272 [DAT](INFO): Starting 2020-05-28T15:39:25.686Z,1590680365.686 [DAT](INFO): Starting 2020-05-28T15:39:26.080Z,1590680366.080 [DAT](INFO): Starting 2020-05-28T15:39:26.487Z,1590680366.487 [DAT](INFO): Starting 2020-05-28T15:39:26.904Z,1590680366.904 [DAT](INFO): Starting 2020-05-28T15:39:27.290Z,1590680367.290 [DAT](INFO): Starting 2020-05-28T15:39:27.694Z,1590680367.694 [DAT](INFO): Starting 2020-05-28T15:39:28.106Z,1590680368.106 [DAT](INFO): Starting 2020-05-28T15:39:28.508Z,1590680368.508 [DAT](INFO): Starting 2020-05-28T15:39:28.909Z,1590680368.909 [DAT](INFO): Starting 2020-05-28T15:39:29.312Z,1590680369.312 [DAT](INFO): Starting 2020-05-28T15:39:29.719Z,1590680369.719 [DAT](INFO): Starting 2020-05-28T15:39:30.124Z,1590680370.124 [DAT](INFO): Starting 2020-05-28T15:39:30.525Z,1590680370.525 [DAT](INFO): Starting 2020-05-28T15:39:30.929Z,1590680370.929 [DAT](INFO): Starting 2020-05-28T15:39:31.335Z,1590680371.335 [DAT](INFO): Starting 2020-05-28T15:39:31.741Z,1590680371.741 [DAT](INFO): Starting 2020-05-28T15:39:32.141Z,1590680372.141 [DAT](INFO): Starting 2020-05-28T15:39:32.545Z,1590680372.545 [DAT](INFO): Starting 2020-05-28T15:39:32.947Z,1590680372.947 [DAT](INFO): Starting 2020-05-28T15:39:33.338Z,1590680373.338 [NAL9602](ERROR): NAL9602 initialization error. 2020-05-28T15:39:33.338Z,1590680373.338 [NAL9602] Communications Fault, FailCount= 1 2020-05-28T15:39:33.338Z,1590680373.338 [NAL9602](ERROR): Communications Fault 2020-05-28T15:39:33.365Z,1590680373.365 [DAT](INFO): Starting 2020-05-28T15:39:33.742Z,1590680373.742 [NAL9602](INFO): Powering down 2020-05-28T15:39:33.757Z,1590680373.757 [DAT](INFO): Starting 2020-05-28T15:39:34.160Z,1590680374.160 [DAT](INFO): Starting 2020-05-28T15:39:34.565Z,1590680374.565 [DAT](INFO): Starting 2020-05-28T15:39:34.972Z,1590680374.972 [DAT](INFO): Starting 2020-05-28T15:39:35.369Z,1590680375.369 [DAT](INFO): Starting 2020-05-28T15:39:35.776Z,1590680375.776 [DAT](INFO): Starting 2020-05-28T15:39:36.180Z,1590680376.180 [DAT](INFO): Starting 2020-05-28T15:39:36.586Z,1590680376.586 [DAT](INFO): Starting 2020-05-28T15:39:37.004Z,1590680377.004 [DAT](INFO): Starting 2020-05-28T15:39:37.389Z,1590680377.389 [DAT](INFO): Starting 2020-05-28T15:39:37.796Z,1590680377.796 [DAT](INFO): Starting 2020-05-28T15:39:38.202Z,1590680378.202 [DAT](INFO): Starting 2020-05-28T15:39:38.610Z,1590680378.610 [DAT](INFO): Starting 2020-05-28T15:39:39.008Z,1590680379.008 [DAT](INFO): Starting 2020-05-28T15:39:39.412Z,1590680379.412 [DAT](INFO): Starting 2020-05-28T15:39:39.814Z,1590680379.814 [DAT](INFO): Starting 2020-05-28T15:39:40.225Z,1590680380.225 [DAT](INFO): Starting 2020-05-28T15:39:40.624Z,1590680380.624 [DAT](INFO): Starting 2020-05-28T15:39:41.029Z,1590680381.029 [DAT](INFO): Starting 2020-05-28T15:39:41.432Z,1590680381.432 [DAT](INFO): Starting 2020-05-28T15:39:41.839Z,1590680381.839 [DAT](INFO): Starting 2020-05-28T15:39:42.244Z,1590680382.244 [DAT](INFO): Starting 2020-05-28T15:39:42.644Z,1590680382.644 [DAT](INFO): Starting 2020-05-28T15:39:43.047Z,1590680383.047 [DAT](INFO): Starting 2020-05-28T15:39:43.454Z,1590680383.454 [DAT](INFO): Starting 2020-05-28T15:39:43.859Z,1590680383.859 [DAT](INFO): Starting 2020-05-28T15:39:44.260Z,1590680384.260 [DAT](INFO): Starting 2020-05-28T15:39:44.664Z,1590680384.664 [DAT](INFO): Starting 2020-05-28T15:39:45.070Z,1590680385.070 [DAT](INFO): Starting 2020-05-28T15:39:45.472Z,1590680385.472 [DAT](INFO): Starting 2020-05-28T15:39:45.877Z,1590680385.877 [DAT](INFO): Starting 2020-05-28T15:39:46.279Z,1590680386.279 [DAT](INFO): Starting 2020-05-28T15:39:46.682Z,1590680386.682 [DAT](INFO): Starting 2020-05-28T15:39:47.114Z,1590680387.114 [DAT](INFO): Starting 2020-05-28T15:39:47.115Z,1590680387.115 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2020-05-28T15:39:47.115Z,1590680387.115 [DAT] Communications Fault, FailCount= 1 2020-05-28T15:39:47.115Z,1590680387.115 [DAT](ERROR): Communications Fault 2020-05-28T15:39:47.162Z,1590680387.162 [SBIT](ERROR): Could not read elevatorAngleReader_. 2020-05-28T15:39:47.162Z,1590680387.162 [SBIT](ERROR): Could not read rudderAngleReader_. 2020-05-28T15:39:47.162Z,1590680387.162 [SBIT](ERROR): Could not read massPosReader_. 2020-05-28T15:39:47.162Z,1590680387.162 [SBIT](FAULT): Control surface position failure. 2020-05-28T15:39:47.492Z,1590680387.492 [DAT](INFO): Stop 2020-05-28T15:39:47.492Z,1590680387.492 [DAT](INFO): uninitialize 2020-05-28T15:39:47.492Z,1590680387.492 [DAT](INFO): Powering down 2020-05-28T15:39:47.896Z,1590680387.896 [DAT](INFO): Stopping 2020-05-28T15:39:48.305Z,1590680388.305 [DAT](INFO): Stopped 2020-05-28T15:40:00.850Z,1590680400.850 [SBIT](ERROR): Could not read elevatorAngleReader_. 2020-05-28T15:40:00.850Z,1590680400.850 [SBIT](ERROR): Could not read rudderAngleReader_. 2020-05-28T15:40:00.850Z,1590680400.850 [SBIT](ERROR): Could not read massPosReader_. 2020-05-28T15:40:00.850Z,1590680400.850 [SBIT](FAULT): Control surface position failure. 2020-05-28T15:40:01.446Z,1590680401.446 [SBIT](CRITICAL): SBIT FAILED 2020-05-28T15:40:01.446Z,1590680401.446 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-05-28T15:40:01.447Z,1590680401.447 [SBIT](IMPORTANT): 2020-05-28T15:40:01.447Z,1590680401.447 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2020-05-28T15:40:01.447Z,1590680401.447 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2020-05-28T15:40:01.447Z,1590680401.447 [SBIT](IMPORTANT): DAT.sbdAddress=9 enum; 2020-05-28T15:40:01.447Z,1590680401.447 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2020-05-28T15:40:01.448Z,1590680401.448 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2020-05-28T15:40:01.448Z,1590680401.448 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2020-05-28T15:40:01.658Z,1590680401.658 [MissionManager](IMPORTANT): Started mission Startup 2020-05-28T15:40:01.659Z,1590680401.659 [Startup] Running Loop=1 2020-05-28T15:40:01.659Z,1590680401.659 [Startup](DEBUG): Aggregate::initialize Startup 2020-05-28T15:40:01.659Z,1590680401.659 [Startup:A.GoToSurface] Running Loop=1 2020-05-28T15:40:01.671Z,1590680401.671 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-28T15:40:01.672Z,1590680401.672 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-05-28T15:40:01.673Z,1590680401.673 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-05-28T15:40:01.673Z,1590680401.673 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-05-28T15:40:01.673Z,1590680401.673 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-28T15:40:01.674Z,1590680401.674 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-28T15:40:01.676Z,1590680401.676 [Startup:StartupSatComms] Running Loop=1 2020-05-28T15:40:01.676Z,1590680401.676 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-05-28T15:40:01.676Z,1590680401.676 [Startup:StartupSatComms:A] Running Loop=1 2020-05-28T15:40:02.072Z,1590680402.072 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-05-28T15:40:46.483Z,1590680446.483 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-05-28T15:40:46.483Z,1590680446.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-05-28T15:40:46.483Z,1590680446.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-05-28T15:41:01.832Z,1590680461.832 [Startup:StartupSatComms:A](INFO): Timed out from 2020-05-28T15:40:01.7Z 2020-05-28T15:41:01.832Z,1590680461.832 [Startup:StartupSatComms:A] Stopped 2020-05-28T15:41:01.832Z,1590680461.832 [Startup:StartupSatComms:B] Running Loop=1 2020-05-28T15:41:02.241Z,1590680462.241 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-05-28T15:42:02.033Z,1590680522.033 [Startup:StartupSatComms:B](INFO): Timed out from 2020-05-28T15:41:01.8Z 2020-05-28T15:42:02.033Z,1590680522.033 [Startup:StartupSatComms:B] Stopped 2020-05-28T15:42:02.034Z,1590680522.034 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-05-28T15:42:02.034Z,1590680522.034 [Startup:StartupSatComms] Stopped 2020-05-28T15:42:02.034Z,1590680522.034 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-05-28T15:42:02.035Z,1590680522.035 [Startup](INFO): Completed Startup 2020-05-28T15:42:02.035Z,1590680522.035 [MissionManager](INFO): Startup is completed. 2020-05-28T15:42:02.035Z,1590680522.035 [MissionManager](INFO): Uninitializing Mission Startup 2020-05-28T15:42:02.035Z,1590680522.035 [Startup] Stopped 2020-05-28T15:42:02.035Z,1590680522.035 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-05-28T15:42:02.036Z,1590680522.036 [Startup:A.GoToSurface] Stopped 2020-05-28T15:42:02.036Z,1590680522.036 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-28T15:42:02.448Z,1590680522.448 [MissionManager](IMPORTANT): Started mission Default 2020-05-28T15:42:02.448Z,1590680522.448 [Default] Running Loop=1 2020-05-28T15:42:02.448Z,1590680522.448 [Default](DEBUG): Aggregate::initialize Default 2020-05-28T15:42:02.449Z,1590680522.449 [Default:B.GoToSurface] Running Loop=1 2020-05-28T15:42:02.449