2019-10-15T22:14:15.805Z,1571177655.805 [Supervisor](DEBUG): Initializing supervisor.
2019-10-15T22:14:15.807Z,1571177655.807 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-15T22:14:15.808Z,1571177655.808 [SyncHandler](INFO): Protected caller Thread ID is 7296
2019-10-15T22:14:15.808Z,1571177655.808 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-15T22:14:15.809Z,1571177655.809 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-15T22:14:15.810Z,1571177655.810 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7297
2019-10-15T22:14:15.812Z,1571177655.812 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-15T22:14:15.824Z,1571177655.824 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-15T22:14:15.825Z,1571177655.825 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-15T22:14:15.825Z,1571177655.825 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7298
2019-10-15T22:14:15.826Z,1571177655.826 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-15T22:14:15.827Z,1571177655.827 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-15T22:14:15.827Z,1571177655.827 [logger ThreadHandler](INFO): Protected caller Thread ID is 7299
2019-10-15T22:14:15.829Z,1571177655.829 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-15T22:14:15.830Z,1571177655.830 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-15T22:14:15.831Z,1571177655.831 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-15T22:14:16.238Z,1571177656.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-15T22:14:16.238Z,1571177656.238 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-15T22:14:16.377Z,1571177656.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-15T22:14:16.378Z,1571177656.378 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-15T22:14:16.456Z,1571177656.456 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-15T22:14:16.552Z,1571177656.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-15T22:14:16.553Z,1571177656.553 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-15T22:14:16.646Z,1571177656.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-15T22:14:16.646Z,1571177656.646 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-15T22:14:17.014Z,1571177657.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-15T22:14:17.014Z,1571177657.014 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-15T22:14:17.113Z,1571177657.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-15T22:14:17.114Z,1571177657.114 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-15T22:14:17.557Z,1571177657.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-15T22:14:17.558Z,1571177657.558 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-15T22:14:17.750Z,1571177657.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-15T22:14:17.751Z,1571177657.751 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-15T22:14:18.043Z,1571177658.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-15T22:14:18.044Z,1571177658.044 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-15T22:14:18.249Z,1571177658.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-15T22:14:18.249Z,1571177658.249 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-15T22:14:18.387Z,1571177658.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-15T22:14:18.387Z,1571177658.387 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-15T22:14:19.007Z,1571177659.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-15T22:14:19.008Z,1571177659.008 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-15T22:14:19.329Z,1571177659.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-15T22:14:19.330Z,1571177659.330 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-15T22:14:19.531Z,1571177659.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-15T22:14:19.533Z,1571177659.533 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-10-15T22:14:19.534Z,1571177659.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-10-15T22:14:19.722Z,1571177659.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-10-15T22:14:19.802Z,1571177659.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-10-15T22:14:19.936Z,1571177659.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-10-15T22:14:20.019Z,1571177660.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-10-15T22:14:20.242Z,1571177660.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-15T22:14:20.243Z,1571177660.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-10-15T22:14:20.335Z,1571177660.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-10-15T22:14:20.440Z,1571177660.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-10-15T22:14:20.544Z,1571177660.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-10-15T22:14:20.712Z,1571177660.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-10-15T22:14:20.908Z,1571177660.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-10-15T22:14:21.149Z,1571177661.149 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:14:21.151Z,1571177661.151 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-15T22:14:21.165Z,1571177661.165 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-15T22:14:21.166Z,1571177661.166 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-15T22:14:21.373Z,1571177661.373 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-15T22:14:21.705Z,1571177661.705 [VerticalControl] Loaded
2019-10-15T22:14:21.705Z,1571177661.705 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-15T22:14:21.706Z,1571177661.706 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-15T22:14:21.780Z,1571177661.780 [HorizontalControl] Loaded
2019-10-15T22:14:21.781Z,1571177661.781 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-15T22:14:21.781Z,1571177661.781 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-15T22:14:21.787Z,1571177661.787 [SpeedControl] Loaded
2019-10-15T22:14:21.787Z,1571177661.787 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-15T22:14:21.788Z,1571177661.788 [LoopControl](DEBUG): Construct LoopControl.
2019-10-15T22:14:21.788Z,1571177661.788 [LoopControl] Loaded
2019-10-15T22:14:21.789Z,1571177661.789 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-15T22:14:21.789Z,1571177661.789 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-15T22:14:21.790Z,1571177661.790 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-15T22:14:21.892Z,1571177661.892 [BuoyancyServo] Loaded
2019-10-15T22:14:21.893Z,1571177661.893 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-15T22:14:21.904Z,1571177661.904 [ElevatorServo] Loaded
2019-10-15T22:14:21.904Z,1571177661.904 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-15T22:14:21.915Z,1571177661.915 [MassServo] Loaded
2019-10-15T22:14:21.915Z,1571177661.915 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-15T22:14:21.926Z,1571177661.926 [RudderServo] Loaded
2019-10-15T22:14:21.927Z,1571177661.927 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-15T22:14:21.937Z,1571177661.937 [ThrusterServo] Loaded
2019-10-15T22:14:21.938Z,1571177661.938 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-15T22:14:21.938Z,1571177661.938 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-15T22:14:21.939Z,1571177661.939 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-15T22:14:21.981Z,1571177661.981 [DepthRateCalculator] Loaded
2019-10-15T22:14:21.981Z,1571177661.981 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-15T22:14:21.987Z,1571177661.987 [PitchRateCalculator] Loaded
2019-10-15T22:14:21.987Z,1571177661.987 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-15T22:14:22.002Z,1571177662.002 [SpeedCalculator] Loaded
2019-10-15T22:14:22.002Z,1571177662.002 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-15T22:14:22.023Z,1571177662.023 [TempGradientCalculator] Loaded
2019-10-15T22:14:22.024Z,1571177662.024 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-15T22:14:22.029Z,1571177662.029 [YawRateCalculator] Loaded
2019-10-15T22:14:22.029Z,1571177662.029 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-15T22:14:22.059Z,1571177662.059 [ElevatorOffsetCalculator] Loaded
2019-10-15T22:14:22.060Z,1571177662.060 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-15T22:14:22.060Z,1571177662.060 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-15T22:14:22.061Z,1571177662.061 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-15T22:14:22.163Z,1571177662.163 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-15T22:14:22.164Z,1571177662.164 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-15T22:14:22.185Z,1571177662.185 [NavChart] Loaded
2019-10-15T22:14:22.185Z,1571177662.185 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-15T22:14:22.189Z,1571177662.189 [UniversalFixResidualReporter] Loaded
2019-10-15T22:14:22.190Z,1571177662.190 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-15T22:14:22.190Z,1571177662.190 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-15T22:14:22.190Z,1571177662.190 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-15T22:14:22.430Z,1571177662.430 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-15T22:14:22.435Z,1571177662.435 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-15T22:14:22.437Z,1571177662.437 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-15T22:14:22.442Z,1571177662.442 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-15T22:14:22.443Z,1571177662.443 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-15T22:14:22.448Z,1571177662.448 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-15T22:14:22.448Z,1571177662.448 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-15T22:14:22.454Z,1571177662.454 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-15T22:14:22.524Z,1571177662.524 [AHRS_M2] Loaded
2019-10-15T22:14:22.525Z,1571177662.525 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-15T22:14:22.601Z,1571177662.601 [DataOverHttps] Loaded
2019-10-15T22:14:22.602Z,1571177662.602 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-15T22:14:22.603Z,1571177662.603 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0
2019-10-15T22:14:22.603Z,1571177662.603 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7379
2019-10-15T22:14:22.617Z,1571177662.617 [Depth_Keller] Loaded
2019-10-15T22:14:22.617Z,1571177662.617 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-15T22:14:22.622Z,1571177662.622 [DropWeight] Loaded
2019-10-15T22:14:22.623Z,1571177662.623 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-15T22:14:22.668Z,1571177662.668 [DVL_micro] Loaded
2019-10-15T22:14:22.669Z,1571177662.669 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-10-15T22:14:22.767Z,1571177662.767 [NAL9602] Loaded
2019-10-15T22:14:22.767Z,1571177662.767 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-15T22:14:22.783Z,1571177662.783 [Onboard] Loaded
2019-10-15T22:14:22.783Z,1571177662.783 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-15T22:14:22.787Z,1571177662.787 [Radio_Surface] Loaded
2019-10-15T22:14:22.787Z,1571177662.787 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-15T22:14:22.788Z,1571177662.788 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408284E0
2019-10-15T22:14:22.788Z,1571177662.788 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7380
2019-10-15T22:14:22.917Z,1571177662.917 [DAT] Loaded
2019-10-15T22:14:22.917Z,1571177662.917 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-15T22:14:24.414Z,1571177664.414 [BPC1] Loaded
2019-10-15T22:14:24.415Z,1571177664.415 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-15T22:14:24.415Z,1571177664.415 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-15T22:14:24.416Z,1571177664.416 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-15T22:14:24.486Z,1571177664.486 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-15T22:14:24.487Z,1571177664.487 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-15T22:14:24.587Z,1571177664.587 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-15T22:14:24.588Z,1571177664.588 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-15T22:14:24.620Z,1571177664.620 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-15T22:14:24.621Z,1571177664.621 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-15T22:14:24.796Z,1571177664.796 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-15T22:14:24.802Z,1571177664.802 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-15T22:14:24.802Z,1571177664.802 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-15T22:14:24.807Z,1571177664.807 [CTD_Seabird](INFO): created writer for : depth
2019-10-15T22:14:24.808Z,1571177664.808 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-15T22:14:24.813Z,1571177664.813 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-15T22:14:24.813Z,1571177664.813 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-15T22:14:24.819Z,1571177664.819 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-15T22:14:24.819Z,1571177664.819 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-15T22:14:24.824Z,1571177664.824 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-15T22:14:24.825Z,1571177664.825 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-15T22:14:24.831Z,1571177664.831 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-15T22:14:24.831Z,1571177664.831 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-15T22:14:24.837Z,1571177664.837 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-15T22:14:24.837Z,1571177664.837 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:14:24.843Z,1571177664.843 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:14:24.843Z,1571177664.843 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-10-15T22:14:24.844Z,1571177664.844 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-10-15T22:14:24.877Z,1571177664.877 [CTD_Seabird] Loaded
2019-10-15T22:14:24.877Z,1571177664.877 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-15T22:14:24.878Z,1571177664.878 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2019-10-15T22:14:24.879Z,1571177664.879 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7381
2019-10-15T22:14:24.910Z,1571177664.910 [ESPComponent] Loaded
2019-10-15T22:14:24.910Z,1571177664.910 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-10-15T22:14:24.924Z,1571177664.924 [PAR_Licor] Loaded
2019-10-15T22:14:24.924Z,1571177664.924 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-15T22:14:24.972Z,1571177664.972 [WetLabsBB2FL] Loaded
2019-10-15T22:14:24.973Z,1571177664.973 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-15T22:14:24.974Z,1571177664.974 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2019-10-15T22:14:24.974Z,1571177664.974 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7382
2019-10-15T22:14:24.975Z,1571177664.975 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-15T22:14:24.975Z,1571177664.975 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-15T22:14:25.274Z,1571177665.274 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-15T22:14:25.275Z,1571177665.275 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-15T22:14:25.410Z,1571177665.410 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-15T22:14:25.421Z,1571177665.421 [SBIT] Loaded
2019-10-15T22:14:25.422Z,1571177665.422 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-15T22:14:25.422Z,1571177665.422 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-15T22:14:25.434Z,1571177665.434 [IBIT] Loaded
2019-10-15T22:14:25.434Z,1571177665.434 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-15T22:14:25.437Z,1571177665.437 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-15T22:14:25.578Z,1571177665.578 [CBIT] Loaded
2019-10-15T22:14:25.578Z,1571177665.578 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-15T22:14:25.578Z,1571177665.578 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-15T22:14:25.595Z,1571177665.595 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-15T22:14:25.596Z,1571177665.596 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-15T22:14:25.626Z,1571177665.626 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-15T22:14:25.627Z,1571177665.627 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-10-15T22:14:25.628Z,1571177665.628 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7383
2019-10-15T22:14:25.636Z,1571177665.636 [Supervisor](INFO): Main Thread ID is 7295
2019-10-15T22:14:25.636Z,1571177665.636 [Supervisor](DEBUG): Running supervisor.
2019-10-15T22:14:25.637Z,1571177665.637 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7384
2019-10-15T22:14:25.640Z,1571177665.640 [controlThread ThreadHandler](INFO): Handler Thread ID is 7385
2019-10-15T22:14:25.641Z,1571177665.641 [controlThread](DEBUG): Initializing ControlThread
2019-10-15T22:14:25.641Z,1571177665.641 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-15T22:14:25.643Z,1571177665.643 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-15T22:14:25.644Z,1571177665.644 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-15T22:14:25.644Z,1571177665.644 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-15T22:14:25.645Z,1571177665.645 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-15T22:14:25.646Z,1571177665.646 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-15T22:14:25.646Z,1571177665.646 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-15T22:14:25.646Z,1571177665.646 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-15T22:14:25.647Z,1571177665.647 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-15T22:14:25.647Z,1571177665.647 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-15T22:14:25.648Z,1571177665.648 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-15T22:14:25.649Z,1571177665.649 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-15T22:14:25.657Z,1571177665.657 [SBIT](INFO): Initialize SBIT Component.
2019-10-15T22:14:25.658Z,1571177665.658 [SBIT](IMPORTANT): git: 2019-09-23_TRN-5-g307b34b
2019-10-15T22:14:25.658Z,1571177665.658 [SBIT](INFO): git hash: 307b34b03e5aa7dd6ba91a7832117416aca74dd3
2019-10-15T22:14:25.658Z,1571177665.658 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-15T22:14:25.659Z,1571177665.659 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-15T22:14:25.660Z,1571177665.660 [SBIT](INFO): Beginning SBIT in 38.000000 seconds.
2019-10-15T22:14:25.661Z,1571177665.661 [IBIT](INFO): Initialize IBIT Component.
2019-10-15T22:14:25.662Z,1571177665.662 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-15T22:14:25.663Z,1571177665.663 [logger ThreadHandler](INFO): Handler Thread ID is 7386
2019-10-15T22:14:25.673Z,1571177665.673 [CBIT](DEBUG): Initialized mux pins.
2019-10-15T22:14:25.673Z,1571177665.673 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-10-15T22:14:25.673Z,1571177665.673 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-15T22:14:25.681Z,1571177665.681 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7387
2019-10-15T22:14:25.682Z,1571177665.682 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-15T22:14:25.693Z,1571177665.693 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7388
2019-10-15T22:14:25.697Z,1571177665.697 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2019-10-15T22:14:25.697Z,1571177665.697 [CBIT] Hardware Fault, FailCount= 1
2019-10-15T22:14:25.697Z,1571177665.697 [CBIT](ERROR): Hardware Fault
2019-10-15T22:14:25.697Z,1571177665.697 [CBIT](DEBUG): Initializing heartbeat.
2019-10-15T22:14:25.705Z,1571177665.705 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7389
2019-10-15T22:14:25.706Z,1571177665.706 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:14:25.709Z,1571177665.709 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:14:25.710Z,1571177665.710 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7391
2019-10-15T22:14:25.712Z,1571177665.712 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:14:25.738Z,1571177665.738 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7392
2019-10-15T22:14:25.741Z,1571177665.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-15T22:14:25.741Z,1571177665.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-15T22:14:25.741Z,1571177665.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-15T22:14:25.742Z,1571177665.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-15T22:14:25.742Z,1571177665.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-15T22:14:25.742Z,1571177665.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-15T22:14:25.742Z,1571177665.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-15T22:14:25.742Z,1571177665.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-15T22:14:25.743Z,1571177665.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-15T22:14:25.743Z,1571177665.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-15T22:14:25.743Z,1571177665.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-15T22:14:25.743Z,1571177665.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-15T22:14:25.743Z,1571177665.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-15T22:14:25.743Z,1571177665.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-15T22:14:25.744Z,1571177665.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-15T22:14:25.744Z,1571177665.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-15T22:14:25.769Z,1571177665.769 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-15T22:14:25.769Z,1571177665.769 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-15T22:14:25.805Z,1571177665.805 [CBIT](DEBUG): Backplane powered.
2019-10-15T22:14:25.806Z,1571177665.806 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-15T22:14:25.815Z,1571177665.815 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:14:25.826Z,1571177665.826 [MissionManager](DEBUG):
2019-10-15T22:14:25.826Z,1571177665.826 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-15T22:14:25.902Z,1571177665.902 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-15T22:14:25.903Z,1571177665.903 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-15T22:14:25.918Z,1571177665.918 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:14:25.958Z,1571177665.958 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-15T22:14:25.960Z,1571177665.960 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-15T22:14:25.984Z,1571177665.984 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-15T22:14:25.987Z,1571177665.987 [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
2019-10-15T22:14:26.005Z,1571177666.005 [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,
2019-10-15T22:14:26.010Z,1571177666.010 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:14:26.079Z,1571177666.079 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-15T22:14:26.079Z,1571177666.079 [DAT](INFO): Powering up
2019-10-15T22:14:26.080Z,1571177666.080 [DAT](DEBUG): Initializing DAT.
2019-10-15T22:14:26.097Z,1571177666.097 [Radio_Surface](INFO): Powering up
2019-10-15T22:14:26.137Z,1571177666.137 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-15T22:14:26.221Z,1571177666.221 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:26.229Z,1571177666.229 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-15T22:14:26.230Z,1571177666.230 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:26.241Z,1571177666.241 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-15T22:14:26.242Z,1571177666.242 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:26.253Z,1571177666.253 [MassServo](DEBUG): Initializing MassServo.
2019-10-15T22:14:26.254Z,1571177666.254 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:26.265Z,1571177666.265 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:14:26.266Z,1571177666.266 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:26.277Z,1571177666.277 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-15T22:14:26.278Z,1571177666.278 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-15T22:14:26.279Z,1571177666.279 [CBIT](DEBUG): Powering off loads.
2019-10-15T22:14:26.293Z,1571177666.293 [CBIT](DEBUG): Disabling WDT.
2019-10-15T22:14:26.305Z,1571177666.305 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-15T22:14:26.542Z,1571177666.542 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2019-10-15T22:14:26.542Z,1571177666.542 [DropWeight] Hardware Fault, FailCount= 1
2019-10-15T22:14:26.542Z,1571177666.542 [DropWeight](ERROR): Hardware Fault
2019-10-15T22:14:27.209Z,1571177667.209 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-15T22:14:27.209Z,1571177667.209 [RudderServo](FAULT): Rudder failed to initialize
2019-10-15T22:14:27.209Z,1571177667.209 [RudderServo] Communications Fault, FailCount= 1
2019-10-15T22:14:27.209Z,1571177667.209 [RudderServo](ERROR): Communications Fault
2019-10-15T22:14:27.533Z,1571177667.533 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-10-15T22:14:27.857Z,1571177667.857 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2019-10-15T22:14:27.857Z,1571177667.857 [ThrusterServo](FAULT): Thruster failed to initialize
2019-10-15T22:14:27.857Z,1571177667.857 [ThrusterServo] Communications Fault, FailCount= 1
2019-10-15T22:14:27.857Z,1571177667.857 [ThrusterServo](ERROR): Communications Fault
2019-10-15T22:14:28.237Z,1571177668.237 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-10-15T22:14:28.237Z,1571177668.237 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-10-15T22:14:28.237Z,1571177668.237 [BuoyancyServo] Communications Fault, FailCount= 1
2019-10-15T22:14:28.237Z,1571177668.237 [BuoyancyServo](ERROR): Communications Fault
2019-10-15T22:14:28.549Z,1571177668.549 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2019-10-15T22:14:28.549Z,1571177668.549 [ElevatorServo](FAULT): Elevator failed to initialize
2019-10-15T22:14:28.549Z,1571177668.549 [ElevatorServo] Communications Fault, FailCount= 1
2019-10-15T22:14:28.549Z,1571177668.549 [ElevatorServo](ERROR): Communications Fault
2019-10-15T22:14:28.550Z,1571177668.550 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:14:28.550Z,1571177668.550 [RudderServo](INFO): Powering down
2019-10-15T22:14:28.632Z,1571177668.632 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-15T22:14:28.632Z,1571177668.632 [ThrusterServo](INFO): Powering down
2019-10-15T22:14:28.747Z,1571177668.747 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-15T22:14:28.748Z,1571177668.748 [ElevatorServo](INFO): Powering down
2019-10-15T22:14:29.417Z,1571177669.417 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:29.488Z,1571177669.488 [ElevatorServo](FAULT): LCB fault: Current Limiter Activated.
2019-10-15T22:14:29.488Z,1571177669.488 [ElevatorServo] Hardware Fault, FailCount= 1
2019-10-15T22:14:29.488Z,1571177669.488 [ElevatorServo](ERROR): Hardware Fault
2019-10-15T22:14:29.489Z,1571177669.489 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:29.610Z,1571177669.610 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:14:30.061Z,1571177670.061 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:30.136Z,1571177670.136 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:30.254Z,1571177670.254 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-15T22:14:30.557Z,1571177670.557 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-10-15T22:14:30.557Z,1571177670.557 [MassServo] Communications Fault, FailCount= 1
2019-10-15T22:14:30.557Z,1571177670.557 [MassServo](ERROR): Communications Fault
2019-10-15T22:14:30.889Z,1571177670.889 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:30.959Z,1571177670.959 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-15T22:14:30.959Z,1571177670.959 [MassServo](INFO): Powering down
2019-10-15T22:14:31.357Z,1571177671.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:31.765Z,1571177671.765 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:31.804Z,1571177671.804 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:14:31.922Z,1571177671.922 [MassServo](DEBUG): Initializing MassServo.
2019-10-15T22:14:32.253Z,1571177672.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:32.653Z,1571177672.653 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:33.045Z,1571177673.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:33.457Z,1571177673.457 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:33.877Z,1571177673.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:34.281Z,1571177674.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:34.677Z,1571177674.677 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:35.097Z,1571177675.097 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:35.497Z,1571177675.497 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:35.894Z,1571177675.894 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:36.305Z,1571177676.305 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:36.709Z,1571177676.709 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:37.145Z,1571177677.145 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:37.545Z,1571177677.545 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:37.953Z,1571177677.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:38.361Z,1571177678.361 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:38.757Z,1571177678.757 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:14:38.757Z,1571177678.757 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-15T22:14:38.757Z,1571177678.757 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:14:38.785Z,1571177678.785 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:14:38.785Z,1571177678.785 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:14:38.785Z,1571177678.785 [AHRS_M2] Communications Fault, FailCount= 1
2019-10-15T22:14:38.785Z,1571177678.785 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:14:38.856Z,1571177678.856 [AHRS_M2](INFO): Powering down
2019-10-15T22:14:39.161Z,1571177679.161 [CTD_Seabird](INFO): Powering down
2019-10-15T22:14:39.713Z,1571177679.713 [CTD_Seabird](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2019-10-15T22:14:39.713Z,1571177679.713 [CTD_Seabird] Hardware Fault, FailCount= 1
2019-10-15T22:14:39.713Z,1571177679.713 [CTD_Seabird](ERROR): Hardware Fault
2019-10-15T22:14:43.717Z,1571177683.717 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:14:43.717Z,1571177683.717 [DVL_micro] Communications Fault, FailCount= 1
2019-10-15T22:14:43.717Z,1571177683.717 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:14:44.185Z,1571177684.185 [DVL_micro](INFO): Powering down
2019-10-15T22:14:52.998Z,1571177692.998 [NAL9602](INFO): Powering up NAL9602
2019-10-15T22:15:04.373Z,1571177704.373 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-15T22:15:13.606Z,1571177713.606 [NAL9602](ERROR): NAL9602 initialization error.
2019-10-15T22:15:13.606Z,1571177713.606 [NAL9602] Communications Fault, FailCount= 1
2019-10-15T22:15:13.606Z,1571177713.606 [NAL9602](ERROR): Communications Fault
2019-10-15T22:15:14.006Z,1571177714.006 [NAL9602](INFO): Powering down
2019-10-15T22:15:17.669Z,1571177717.669 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-10-15T22:15:17.669Z,1571177717.669 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-10-15T22:15:17.669Z,1571177717.669 [SBIT](ERROR): Could not read massPosReader_.
2019-10-15T22:15:26.144Z,1571177726.144 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available:
2019-10-15T22:15:26.144Z,1571177726.144 [DAT] Communications Fault, FailCount= 1
2019-10-15T22:15:26.144Z,1571177726.144 [DAT](ERROR): Communications Fault
2019-10-15T22:15:26.544Z,1571177726.544 [DAT](INFO): Powering down
2019-10-15T22:15:26.951Z,1571177726.951 [DAT](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2019-10-15T22:15:26.951Z,1571177726.951 [DAT] Hardware Fault, FailCount= 1
2019-10-15T22:15:26.951Z,1571177726.951 [DAT](ERROR): Hardware Fault
2019-10-15T22:15:44.329Z,1571177744.329 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-10-15T22:15:44.329Z,1571177744.329 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-10-15T22:15:44.329Z,1571177744.329 [SBIT](ERROR): Could not read massPosReader_.
2019-10-15T22:15:44.329Z,1571177744.329 [SBIT](FAULT): Control surface position failure.
2019-10-15T22:15:52.810Z,1571177752.810 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-15T22:15:58.071Z,1571177758.071 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-10-15T22:15:58.071Z,1571177758.071 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-10-15T22:15:58.071Z,1571177758.071 [SBIT](ERROR): Could not read massPosReader_.
2019-10-15T22:15:58.071Z,1571177758.071 [SBIT](FAULT): Control surface position failure.
2019-10-15T22:15:58.477Z,1571177758.477 [SBIT](CRITICAL): SBIT FAILED
2019-10-15T22:15:58.495Z,1571177758.495 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:15:58.496Z,1571177758.496 [CommandLine](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:15:58.874Z,1571177758.874 [MissionManager](IMPORTANT): Started mission Startup
2019-10-15T22:15:58.874Z,1571177758.874 [Startup] Running Loop=1
2019-10-15T22:15:58.874Z,1571177758.874 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-15T22:15:58.874Z,1571177758.874 [Startup:A.GoToSurface] Running Loop=1
2019-10-15T22:15:58.874Z,1571177758.874 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-15T22:15:58.875Z,1571177758.875 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-15T22:15:58.875Z,1571177758.875 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-15T22:15:58.876Z,1571177758.876 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-15T22:15:58.876Z,1571177758.876 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-15T22:15:58.876Z,1571177758.876 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-15T22:15:58.878Z,1571177758.878 [Startup:StartupSatComms] Running Loop=1
2019-10-15T22:15:58.878Z,1571177758.878 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-15T22:15:58.878Z,1571177758.878 [Startup:StartupSatComms:A] Running Loop=1
2019-10-15T22:15:59.290Z,1571177759.290 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-15T22:15:59.676Z,1571177759.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-15T22:16:12.834Z,1571177772.834 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:16:12.835Z,1571177772.835 [CommandLine](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:16:14.624Z,1571177774.624 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-15T22:16:25.940Z,1571177785.940 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-15T22:16:25.941Z,1571177785.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-15T22:16:25.941Z,1571177785.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-15T22:16:29.675Z,1571177789.675 [CommandLine](IMPORTANT): got command configSet DVL_micro.loadAtStartup 0.000000 bool persist
2019-10-15T22:16:29.675Z,1571177789.675 [CommandLine](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect.
2019-10-15T22:16:29.676Z,1571177789.676 [CommandLine](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2019-10-15T22:16:29.972Z,1571177789.972 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-15T22:16:45.328Z,1571177805.328 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-15T22:16:46.882Z,1571177806.882 [CommandLine](IMPORTANT): got command restart system
2019-10-15T22:16:48.937Z,1571177808.937 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-10-15T22:16:48.937Z,1571177808.937 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:48.938Z,1571177808.938 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.129Z,1571177809.129 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-10-15T22:16:49.129Z,1571177809.129 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.130Z,1571177809.130 [CommandLine](INFO): Join timeout helper Thread ID is 7408
2019-10-15T22:16:49.130Z,1571177809.130 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-15T22:16:49.130Z,1571177809.130 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.131Z,1571177809.131 [NavChartDb](INFO): Join timeout helper Thread ID is 7409
2019-10-15T22:16:49.149Z,1571177809.149 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:49.149Z,1571177809.149 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.169Z,1571177809.169 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-15T22:16:49.169Z,1571177809.169 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.169Z,1571177809.169 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7410
2019-10-15T22:16:49.361Z,1571177809.361 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:49.362Z,1571177809.362 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:16:49.362Z,1571177809.362 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.370Z,1571177809.370 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-15T22:16:49.370Z,1571177809.370 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.370Z,1571177809.370 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7411
2019-10-15T22:16:49.445Z,1571177809.445 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:49.445Z,1571177809.445 [CTD_Seabird](INFO): Powering down
2019-10-15T22:16:49.457Z,1571177809.457 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.469Z,1571177809.469 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-15T22:16:49.469Z,1571177809.469 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.469Z,1571177809.469 [Radio_Surface](INFO): Join timeout helper Thread ID is 7412
2019-10-15T22:16:49.517Z,1571177809.517 [Radio_Surface](INFO): Powering down
2019-10-15T22:16:49.518Z,1571177809.518 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:49.518Z,1571177809.518 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.530Z,1571177809.530 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-15T22:16:49.530Z,1571177809.530 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:49.530Z,1571177809.530 [DataOverHttps](INFO): Join timeout helper Thread ID is 7413
2019-10-15T22:16:50.285Z,1571177810.285 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:50.285Z,1571177810.285 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:50.289Z,1571177810.289 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-15T22:16:50.290Z,1571177810.290 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:50.290Z,1571177810.290 [logger](INFO): Join timeout helper Thread ID is 7414
2019-10-15T22:16:50.297Z,1571177810.297 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:50.297Z,1571177810.297 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:50.310Z,1571177810.310 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-15T22:16:50.310Z,1571177810.310 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:50.310Z,1571177810.310 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-15T22:16:50.310Z,1571177810.310 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:50.310Z,1571177810.310 [controlThread](INFO): Join timeout helper Thread ID is 7415
2019-10-15T22:16:50.557Z,1571177810.557 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:16:50.557Z,1571177810.557 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-15T22:16:50.557Z,1571177810.557 [AHRS_M2](INFO): Powering down
2019-10-15T22:16:50.559Z,1571177810.559 [DVL_micro](INFO): Powering down
2019-10-15T22:16:50.559Z,1571177810.559 [NAL9602](INFO): Powering down
2019-10-15T22:16:50.560Z,1571177810.560 [DAT](INFO): Powering down
2019-10-15T22:16:50.584Z,1571177810.584 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-15T22:16:50.585Z,1571177810.585 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-15T22:16:50.585Z,1571177810.585 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-15T22:16:50.586Z,1571177810.586 [MissionManager](INFO): Uninitializing Mission Default
2019-10-15T22:16:50.588Z,1571177810.588 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-15T22:16:50.588Z,1571177810.588 [Startup] Stopped
2019-10-15T22:16:50.588Z,1571177810.588 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-15T22:16:50.588Z,1571177810.588 [Startup:A.GoToSurface] Stopped
2019-10-15T22:16:50.589Z,1571177810.589 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-15T22:16:50.589Z,1571177810.589 [Startup:StartupSatComms] Stopped
2019-10-15T22:16:50.589Z,1571177810.589 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-15T22:16:50.589Z,1571177810.589 [Startup:StartupSatComms:A] Stopped
2019-10-15T22:16:50.590Z,1571177810.590 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-15T22:16:50.591Z,1571177810.591 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-15T22:16:50.591Z,1571177810.591 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-15T22:16:50.591Z,1571177810.591 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-15T22:16:50.592Z,1571177810.592 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-15T22:16:50.592Z,1571177810.592 [BuoyancyServo](INFO): Powering down
2019-10-15T22:16:50.745Z,1571177810.745 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-15T22:16:50.745Z,1571177810.745 [ElevatorServo](INFO): Powering down
2019-10-15T22:16:50.746Z,1571177810.746 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-15T22:16:50.746Z,1571177810.746 [MassServo](INFO): Powering down
2019-10-15T22:16:50.747Z,1571177810.747 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:16:50.747Z,1571177810.747 [RudderServo](INFO): Powering down
2019-10-15T22:16:50.748Z,1571177810.748 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-15T22:16:50.748Z,1571177810.748 [ThrusterServo](INFO): Powering down
2019-10-15T22:16:50.749Z,1571177810.749 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-15T22:16:50.750Z,1571177810.750 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-15T22:16:50.750Z,1571177810.750 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-15T22:16:50.750Z,1571177810.750 [CBIT](DEBUG): Powering off loads.
2019-10-15T22:16:50.761Z,1571177810.761 [CBIT](DEBUG): Disabling WDT.
2019-10-15T22:16:50.773Z,1571177810.773 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-15T22:16:50.773Z,1571177810.773 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:51.220Z,1571177811.220 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:51.228Z,1571177811.228 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:51.264Z,1571177811.264 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:51.268Z,1571177811.268 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:51.303Z,1571177811.303 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:16:51.362Z,1571177811.362 [logger ThreadHandler](INFO): Thread cancelled.