2020-06-09T04:13:27.767Z,1591676007.767 [Supervisor](DEBUG): Initializing supervisor.
2020-06-09T04:13:27.770Z,1591676007.770 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-06-09T04:13:27.771Z,1591676007.771 [SyncHandler](INFO): Protected caller Thread ID is 8654
2020-06-09T04:13:27.771Z,1591676007.771 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-06-09T04:13:27.772Z,1591676007.772 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-06-09T04:13:27.772Z,1591676007.772 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8655
2020-06-09T04:13:27.775Z,1591676007.775 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-06-09T04:13:27.788Z,1591676007.788 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-06-09T04:13:27.789Z,1591676007.789 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-06-09T04:13:27.789Z,1591676007.789 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8656
2020-06-09T04:13:27.790Z,1591676007.790 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-06-09T04:13:27.791Z,1591676007.791 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-06-09T04:13:27.792Z,1591676007.792 [logger ThreadHandler](INFO): Protected caller Thread ID is 8657
2020-06-09T04:13:27.794Z,1591676007.794 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-06-09T04:13:27.794Z,1591676007.794 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-06-09T04:13:27.796Z,1591676007.796 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-06-09T04:13:27.998Z,1591676007.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-06-09T04:13:27.998Z,1591676007.998 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-06-09T04:13:28.076Z,1591676008.076 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-06-09T04:13:28.491Z,1591676008.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-06-09T04:13:28.492Z,1591676008.492 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-06-09T04:13:28.795Z,1591676008.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-06-09T04:13:28.796Z,1591676008.796 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-06-09T04:13:28.889Z,1591676008.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-06-09T04:13:28.890Z,1591676008.890 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-06-09T04:13:29.242Z,1591676009.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-06-09T04:13:29.242Z,1591676009.242 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-06-09T04:13:29.429Z,1591676009.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-06-09T04:13:29.430Z,1591676009.430 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-06-09T04:13:29.875Z,1591676009.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-06-09T04:13:29.876Z,1591676009.876 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-06-09T04:13:29.975Z,1591676009.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-06-09T04:13:29.976Z,1591676009.976 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-06-09T04:13:30.074Z,1591676010.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-06-09T04:13:30.075Z,1591676010.075 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-06-09T04:13:30.702Z,1591676010.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-06-09T04:13:30.703Z,1591676010.703 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-06-09T04:13:31.114Z,1591676011.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-06-09T04:13:31.115Z,1591676011.115 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-06-09T04:13:31.296Z,1591676011.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-06-09T04:13:31.296Z,1591676011.296 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-06-09T04:13:31.440Z,1591676011.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-06-09T04:13:31.440Z,1591676011.440 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-06-09T04:13:31.582Z,1591676011.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-06-09T04:13:31.583Z,1591676011.583 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-06-09T04:13:31.784Z,1591676011.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-06-09T04:13:31.785Z,1591676011.785 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2020-06-09T04:13:31.786Z,1591676011.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2020-06-09T04:13:31.874Z,1591676011.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2020-06-09T04:13:31.956Z,1591676011.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2020-06-09T04:13:32.052Z,1591676012.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2020-06-09T04:13:32.132Z,1591676012.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2020-06-09T04:13:32.224Z,1591676012.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2020-06-09T04:13:32.320Z,1591676012.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2020-06-09T04:13:32.566Z,1591676012.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2020-06-09T04:13:32.723Z,1591676012.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2020-06-09T04:13:33.126Z,1591676013.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2020-06-09T04:13:33.313Z,1591676013.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2020-06-09T04:13:33.490Z,1591676013.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2020-06-09T04:13:33.730Z,1591676013.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-06-09T04:13:33.731Z,1591676013.731 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2020-06-09T04:13:33.732Z,1591676013.732 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-06-09T04:13:33.738Z,1591676013.738 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-06-09T04:13:33.789Z,1591676013.789 [VerticalControl](DEBUG): Construct VerticalControl.
2020-06-09T04:13:33.906Z,1591676013.906 [VerticalControl] Loaded
2020-06-09T04:13:33.906Z,1591676013.906 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-06-09T04:13:33.907Z,1591676013.907 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-06-09T04:13:33.990Z,1591676013.990 [HorizontalControl] Loaded
2020-06-09T04:13:33.991Z,1591676013.991 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-06-09T04:13:33.991Z,1591676013.991 [SpeedControl](DEBUG): Construct SpeedControl.
2020-06-09T04:13:33.997Z,1591676013.997 [SpeedControl] Loaded
2020-06-09T04:13:33.997Z,1591676013.997 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-06-09T04:13:33.998Z,1591676013.998 [LoopControl](DEBUG): Construct LoopControl.
2020-06-09T04:13:33.998Z,1591676013.998 [LoopControl] Loaded
2020-06-09T04:13:33.999Z,1591676013.999 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-06-09T04:13:33.999Z,1591676013.999 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-06-09T04:13:33.000Z,1591676014.000 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-06-09T04:13:34.019Z,1591676014.019 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-06-09T04:13:34.019Z,1591676014.019 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-06-09T04:13:34.121Z,1591676014.121 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-06-09T04:13:34.121Z,1591676014.121 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-06-09T04:13:34.227Z,1591676014.227 [BuoyancyServo] Loaded
2020-06-09T04:13:34.227Z,1591676014.227 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-06-09T04:13:34.238Z,1591676014.238 [ElevatorServo] Loaded
2020-06-09T04:13:34.239Z,1591676014.239 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-06-09T04:13:34.249Z,1591676014.249 [MassServo] Loaded
2020-06-09T04:13:34.250Z,1591676014.250 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-06-09T04:13:34.261Z,1591676014.261 [RudderServo] Loaded
2020-06-09T04:13:34.261Z,1591676014.261 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-06-09T04:13:34.272Z,1591676014.272 [ThrusterServo] Loaded
2020-06-09T04:13:34.273Z,1591676014.273 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-06-09T04:13:34.273Z,1591676014.273 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-06-09T04:13:34.274Z,1591676014.274 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-06-09T04:13:34.382Z,1591676014.382 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-06-09T04:13:34.382Z,1591676014.382 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-06-09T04:13:34.403Z,1591676014.403 [NavChart] Loaded
2020-06-09T04:13:34.403Z,1591676014.403 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-06-09T04:13:34.407Z,1591676014.407 [UniversalFixResidualReporter] Loaded
2020-06-09T04:13:34.408Z,1591676014.408 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-06-09T04:13:34.408Z,1591676014.408 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-06-09T04:13:34.409Z,1591676014.409 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-06-09T04:13:34.492Z,1591676014.492 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-06-09T04:13:34.493Z,1591676014.493 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-06-09T04:13:34.784Z,1591676014.784 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-06-09T04:13:34.789Z,1591676014.789 [AHRS_M2](INFO): created writer for : platform_orientation
2020-06-09T04:13:34.791Z,1591676014.791 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-06-09T04:13:34.796Z,1591676014.796 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-06-09T04:13:34.797Z,1591676014.797 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-06-09T04:13:34.802Z,1591676014.802 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-06-09T04:13:34.803Z,1591676014.803 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-06-09T04:13:34.808Z,1591676014.808 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-06-09T04:13:34.882Z,1591676014.882 [AHRS_M2] Loaded
2020-06-09T04:13:34.882Z,1591676014.882 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-06-09T04:13:34.962Z,1591676014.962 [DataOverHttps] Loaded
2020-06-09T04:13:34.962Z,1591676014.962 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-06-09T04:13:34.963Z,1591676014.963 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2020-06-09T04:13:34.964Z,1591676014.964 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8743
2020-06-09T04:13:34.977Z,1591676014.977 [Depth_Keller] Loaded
2020-06-09T04:13:34.978Z,1591676014.978 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-06-09T04:13:34.983Z,1591676014.983 [DropWeight] Loaded
2020-06-09T04:13:34.983Z,1591676014.983 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-06-09T04:13:35.084Z,1591676015.084 [NAL9602] Loaded
2020-06-09T04:13:35.085Z,1591676015.085 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-06-09T04:13:35.133Z,1591676015.133 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-06-09T04:13:35.133Z,1591676015.133 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-06-09T04:13:35.134Z,1591676015.134 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-06-09T04:13:35.134Z,1591676015.134 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-06-09T04:13:35.135Z,1591676015.135 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-06-09T04:13:35.135Z,1591676015.135 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-06-09T04:13:35.136Z,1591676015.136 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-06-09T04:13:35.136Z,1591676015.136 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-06-09T04:13:35.137Z,1591676015.137 [Onboard] Loaded
2020-06-09T04:13:35.137Z,1591676015.137 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-06-09T04:13:35.138Z,1591676015.138 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2020-06-09T04:13:35.138Z,1591676015.138 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8744
2020-06-09T04:13:35.142Z,1591676015.142 [Radio_Surface] Loaded
2020-06-09T04:13:35.142Z,1591676015.142 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-06-09T04:13:35.143Z,1591676015.143 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2020-06-09T04:13:35.143Z,1591676015.143 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8745
2020-06-09T04:13:35.283Z,1591676015.283 [DAT] Loaded
2020-06-09T04:13:35.283Z,1591676015.283 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-06-09T04:13:36.848Z,1591676016.848 [BPC1] Loaded
2020-06-09T04:13:36.848Z,1591676016.848 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-06-09T04:13:36.848Z,1591676016.848 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-06-09T04:13:36.849Z,1591676016.849 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-06-09T04:13:36.890Z,1591676016.890 [DepthRateCalculator] Loaded
2020-06-09T04:13:36.890Z,1591676016.890 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-06-09T04:13:36.895Z,1591676016.895 [PitchRateCalculator] Loaded
2020-06-09T04:13:36.896Z,1591676016.896 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-06-09T04:13:36.908Z,1591676016.908 [SpeedCalculator] Loaded
2020-06-09T04:13:36.908Z,1591676016.908 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-06-09T04:13:36.930Z,1591676016.930 [TempGradientCalculator] Loaded
2020-06-09T04:13:36.930Z,1591676016.930 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-06-09T04:13:36.935Z,1591676016.935 [YawRateCalculator] Loaded
2020-06-09T04:13:36.936Z,1591676016.936 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-06-09T04:13:36.967Z,1591676016.967 [ElevatorOffsetCalculator] Loaded
2020-06-09T04:13:36.967Z,1591676016.967 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-06-09T04:13:36.968Z,1591676016.968 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-06-09T04:13:36.968Z,1591676016.968 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-06-09T04:13:37.165Z,1591676017.165 [CANONSampler] Loaded
2020-06-09T04:13:37.165Z,1591676017.165 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-06-09T04:13:37.174Z,1591676017.174 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-06-09T04:13:37.180Z,1591676017.180 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-06-09T04:13:37.180Z,1591676017.180 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-06-09T04:13:37.185Z,1591676017.185 [CTD_Seabird](INFO): created writer for : depth
2020-06-09T04:13:37.186Z,1591676017.186 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-06-09T04:13:37.191Z,1591676017.191 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-06-09T04:13:37.191Z,1591676017.191 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-06-09T04:13:37.197Z,1591676017.197 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-06-09T04:13:37.197Z,1591676017.197 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-06-09T04:13:37.203Z,1591676017.203 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-06-09T04:13:37.203Z,1591676017.203 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-06-09T04:13:37.209Z,1591676017.209 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-06-09T04:13:37.209Z,1591676017.209 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-06-09T04:13:37.215Z,1591676017.215 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-06-09T04:13:37.215Z,1591676017.215 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-06-09T04:13:37.221Z,1591676017.221 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-06-09T04:13:37.221Z,1591676017.221 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-06-09T04:13:37.222Z,1591676017.222 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-06-09T04:13:37.255Z,1591676017.255 [CTD_Seabird] Loaded
2020-06-09T04:13:37.255Z,1591676017.255 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-06-09T04:13:37.257Z,1591676017.257 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409FE4E0
2020-06-09T04:13:37.257Z,1591676017.257 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8746
2020-06-09T04:13:37.273Z,1591676017.273 [PAR_Licor] Loaded
2020-06-09T04:13:37.273Z,1591676017.273 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-06-09T04:13:37.279Z,1591676017.279 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-06-09T04:13:37.279Z,1591676017.279 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-06-09T04:13:37.284Z,1591676017.284 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-06-09T04:13:37.284Z,1591676017.284 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-06-09T04:13:37.288Z,1591676017.288 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-06-09T04:13:37.289Z,1591676017.289 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-06-09T04:13:37.293Z,1591676017.293 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-06-09T04:13:37.293Z,1591676017.293 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-06-09T04:13:37.298Z,1591676017.298 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-06-09T04:13:37.298Z,1591676017.298 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-06-09T04:13:37.303Z,1591676017.303 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-06-09T04:13:37.303Z,1591676017.303 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-06-09T04:13:37.307Z,1591676017.307 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-06-09T04:13:37.307Z,1591676017.307 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-06-09T04:13:37.312Z,1591676017.312 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.317Z,1591676017.317 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.317Z,1591676017.317 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.317Z,1591676017.317 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.322Z,1591676017.322 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.322Z,1591676017.322 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.326Z,1591676017.326 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.326Z,1591676017.326 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T04:13:37.331Z,1591676017.331 [WetLabsBB2FL] Loaded
2020-06-09T04:13:37.331Z,1591676017.331 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-06-09T04:13:37.332Z,1591676017.332 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A2E4E0
2020-06-09T04:13:37.332Z,1591676017.332 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8747
2020-06-09T04:13:37.333Z,1591676017.333 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-06-09T04:13:37.334Z,1591676017.334 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-06-09T04:13:37.361Z,1591676017.361 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-06-09T04:13:37.361Z,1591676017.361 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-06-09T04:13:37.677Z,1591676017.677 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-06-09T04:13:37.678Z,1591676017.678 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-06-09T04:13:37.906Z,1591676017.906 [SBIT](DEBUG): Construct Startup Built In Test.
2020-06-09T04:13:37.918Z,1591676017.918 [SBIT] Loaded
2020-06-09T04:13:37.918Z,1591676017.918 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-06-09T04:13:37.919Z,1591676017.919 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-06-09T04:13:37.931Z,1591676017.931 [IBIT] Loaded
2020-06-09T04:13:37.932Z,1591676017.932 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-06-09T04:13:37.935Z,1591676017.935 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-06-09T04:13:38.078Z,1591676018.078 [CBIT] Loaded
2020-06-09T04:13:38.079Z,1591676018.079 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-06-09T04:13:38.079Z,1591676018.079 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-06-09T04:13:38.080Z,1591676018.080 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-06-09T04:13:38.158Z,1591676018.158 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-06-09T04:13:38.161Z,1591676018.161 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-06-09T04:13:38.162Z,1591676018.162 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-06-09T04:13:38.169Z,1591676018.169 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-06-09T04:13:38.170Z,1591676018.170 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5F4E0
2020-06-09T04:13:38.170Z,1591676018.170 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8748
2020-06-09T04:13:38.175Z,1591676018.175 [Supervisor](INFO): Main Thread ID is 8653
2020-06-09T04:13:38.175Z,1591676018.175 [Supervisor](DEBUG): Running supervisor.
2020-06-09T04:13:38.176Z,1591676018.176 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8749
2020-06-09T04:13:38.178Z,1591676018.178 [controlThread ThreadHandler](INFO): Handler Thread ID is 8750
2020-06-09T04:13:38.179Z,1591676018.179 [controlThread](DEBUG): Initializing ControlThread
2020-06-09T04:13:38.179Z,1591676018.179 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-06-09T04:13:38.181Z,1591676018.181 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-06-09T04:13:38.182Z,1591676018.182 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-06-09T04:13:38.182Z,1591676018.182 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-06-09T04:13:38.184Z,1591676018.184 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-06-09T04:13:38.184Z,1591676018.184 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-06-09T04:13:38.189Z,1591676018.189 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-06-09T04:13:38.189Z,1591676018.189 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-06-09T04:13:38.189Z,1591676018.189 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-06-09T04:13:38.190Z,1591676018.190 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-06-09T04:13:38.190Z,1591676018.190 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-06-09T04:13:38.190Z,1591676018.190 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-06-09T04:13:38.195Z,1591676018.195 [SBIT](INFO): Initialize SBIT Component.
2020-06-09T04:13:38.196Z,1591676018.196 [SBIT](IMPORTANT): git: 2020-06-05
2020-06-09T04:13:38.196Z,1591676018.196 [SBIT](INFO): git hash: c12bfff2bdf9b929af60ba43c4eb841aed31d072
2020-06-09T04:13:38.196Z,1591676018.196 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-06-09T04:13:38.198Z,1591676018.198 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2020-06-09T04:13:38.199Z,1591676018.199 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2020-06-09T04:13:38.199Z,1591676018.199 [IBIT](INFO): Initialize IBIT Component.
2020-06-09T04:13:38.200Z,1591676018.200 [CBIT](DEBUG): Initialize CBIT Component.
2020-06-09T04:13:38.201Z,1591676018.201 [logger ThreadHandler](INFO): Handler Thread ID is 8751
2020-06-09T04:13:38.215Z,1591676018.215 [CBIT](DEBUG): Initialized mux pins.
2020-06-09T04:13:38.215Z,1591676018.215 [CBIT](DEBUG): Initializing the watchdog timer.
2020-06-09T04:13:38.219Z,1591676018.219 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8752
2020-06-09T04:13:38.220Z,1591676018.220 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-06-09T04:13:38.227Z,1591676018.227 [Onboard ThreadHandler](INFO): Handler Thread ID is 8753
2020-06-09T04:13:38.240Z,1591676018.240 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-06-09T04:13:38.240Z,1591676018.240 [CBIT](DEBUG): Initializing heartbeat.
2020-06-09T04:13:38.247Z,1591676018.247 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8754
2020-06-09T04:13:38.255Z,1591676018.255 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8755
2020-06-09T04:13:38.256Z,1591676018.256 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-06-09T04:13:38.259Z,1591676018.259 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-06-09T04:13:38.260Z,1591676018.260 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8757
2020-06-09T04:13:38.261Z,1591676018.261 [WetLabsBB2FL](INFO): Powering down
2020-06-09T04:13:38.296Z,1591676018.296 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8758
2020-06-09T04:13:38.299Z,1591676018.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-06-09T04:13:38.299Z,1591676018.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-06-09T04:13:38.300Z,1591676018.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-06-09T04:13:38.300Z,1591676018.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-06-09T04:13:38.300Z,1591676018.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-06-09T04:13:38.300Z,1591676018.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-06-09T04:13:38.300Z,1591676018.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-06-09T04:13:38.300Z,1591676018.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-06-09T04:13:38.301Z,1591676018.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-06-09T04:13:38.301Z,1591676018.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-06-09T04:13:38.301Z,1591676018.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-06-09T04:13:38.301Z,1591676018.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-06-09T04:13:38.301Z,1591676018.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-06-09T04:13:38.302Z,1591676018.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-06-09T04:13:38.302Z,1591676018.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-06-09T04:13:38.302Z,1591676018.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-06-09T04:13:38.311Z,1591676018.311 [CBIT](DEBUG): Deactivating GF circuits.
2020-06-09T04:13:38.311Z,1591676018.311 [CBIT](DEBUG): Deactivating emergency mode.
2020-06-09T04:13:38.347Z,1591676018.347 [CBIT](DEBUG): Backplane powered.
2020-06-09T04:13:38.348Z,1591676018.348 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-06-09T04:13:38.358Z,1591676018.358 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-09T04:13:38.369Z,1591676018.369 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2020-06-09T04:13:38.370Z,1591676018.370 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-06-09T04:13:38.445Z,1591676018.445 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-06-09T04:13:38.446Z,1591676018.446 [Default:A.Wait](DEBUG): Construct Wait.
2020-06-09T04:13:38.464Z,1591676018.464 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-09T04:13:38.503Z,1591676018.503 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-06-09T04:13:38.506Z,1591676018.506 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-06-09T04:13:38.528Z,1591676018.528 [Default:E.Execute](DEBUG): Construct Execute.
2020-06-09T04:13:38.547Z,1591676018.547 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2020-06-09T04:13:38.553Z,1591676018.553 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,CANONSampler,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-06-09T04:13:38.566Z,1591676018.566 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-06-09T04:13:38.604Z,1591676018.604 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2020-06-09T04:13:38.607Z,1591676018.607 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-06-09T04:13:38.607Z,1591676018.607 [DAT](INFO): Powering up
2020-06-09T04:13:38.608Z,1591676018.608 [DAT](DEBUG): Initializing DAT.
2020-06-09T04:13:38.651Z,1591676018.651 [Radio_Surface](INFO): Powering up
2020-06-09T04:13:38.655Z,1591676018.655 [CANONSampler](INFO): Powering down
2020-06-09T04:13:38.675Z,1591676018.675 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-06-09T04:13:38.753Z,1591676018.753 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-06-09T04:13:38.845Z,1591676018.845 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-06-09T04:13:38.851Z,1591676018.851 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-06-09T04:13:38.852Z,1591676018.852 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-06-09T04:13:38.859Z,1591676018.859 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-06-09T04:13:38.860Z,1591676018.860 [MassServo](DEBUG): Initializing EZServoServo.
2020-06-09T04:13:38.867Z,1591676018.867 [MassServo](DEBUG): Initializing MassServo.
2020-06-09T04:13:38.868Z,1591676018.868 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-09T04:13:38.875Z,1591676018.875 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-09T04:13:38.876Z,1591676018.876 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-06-09T04:13:38.883Z,1591676018.883 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-06-09T04:13:39.687Z,1591676019.687 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-06-09T04:13:39.687Z,1591676019.687 [RudderServo](FAULT): Rudder failed to initialize
2020-06-09T04:13:39.687Z,1591676019.687 [RudderServo] Communications Fault, FailCount= 1
2020-06-09T04:13:39.687Z,1591676019.687 [RudderServo](ERROR): Communications Fault
2020-06-09T04:13:39.858Z,1591676019.858 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-06-09T04:13:40.048Z,1591676020.048 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-09T04:13:40.048Z,1591676020.048 [RudderServo](INFO): Powering down
2020-06-09T04:13:40.713Z,1591676020.713 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-09T04:13:40.832Z,1591676020.832 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-09T04:13:40.836Z,1591676020.836 [CBIT](INFO): Clearing failed state for component RudderServo
2020-06-09T04:13:40.836Z,1591676020.836 [RudderServo] No Fault, FailCount= 1
2020-06-09T04:13:45.295Z,1591676025.295 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-06-09T04:13:50.745Z,1591676030.745 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004810
2020-06-09T04:13:50.805Z,1591676030.805 [DAT](DEBUG): 
2020-06-09T04:13:50.806Z,1591676030.806 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2020-06-09T04:13:52.017Z,1591676032.017 [DAT](DEBUG): MF Frequency Band
2020-06-09T04:13:52.018Z,1591676032.018 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21
2020-06-09T04:13:52.018Z,1591676032.018 [DAT](DEBUG): Jan  4 2000 03:11:56 
2020-06-09T04:13:52.838Z,1591676032.838 [DAT](DEBUG): Features enabled [Bearing]
2020-06-09T04:13:52.839Z,1591676032.839 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2020-06-09T04:13:52.840Z,1591676032.840 [DAT](INFO): commRate: 800
2020-06-09T04:13:53.229Z,1591676033.229 [DAT](INFO): entering command mode
2020-06-09T04:13:53.632Z,1591676033.632 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T04:13:53.633Z,1591676033.633 [DAT](INFO): command mode acknowledged
2020-06-09T04:13:53.633Z,1591676033.633 [DAT](INFO): setting verbose to 3
2020-06-09T04:13:54.036Z,1591676034.036 [DAT](DEBUG): checking for verbose setting acknowledgment
2020-06-09T04:13:54.037Z,1591676034.037 [DAT](INFO): set verbose to 3
2020-06-09T04:13:54.441Z,1591676034.441 [DAT](INFO): setting transmit power to 8
2020-06-09T04:13:54.844Z,1591676034.844 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-06-09T04:13:55.256Z,1591676035.256 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-06-09T04:13:55.257Z,1591676035.257 [DAT](INFO): set transmit power to 8
2020-06-09T04:13:55.652Z,1591676035.652 [DAT](INFO): setting local address to 7
2020-06-09T04:13:56.056Z,1591676036.056 [DAT](DEBUG): checking for local address setting acknowledgment
2020-06-09T04:13:56.460Z,1591676036.460 [DAT](DEBUG): checking for local address setting acknowledgment
2020-06-09T04:13:56.461Z,1591676036.461 [DAT](INFO): set local address to 7
2020-06-09T04:14:05.345Z,1591676045.345 [NAL9602](INFO): Powering up NAL9602
2020-06-09T04:14:16.253Z,1591676056.253 [NAL9602](INFO): NAL9602 initialized
2020-06-09T04:14:54.654Z,1591676094.654 [SBIT](IMPORTANT): Beginning Startup BIT
2020-06-09T04:14:54.658Z,1591676094.658 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-09T04:15:05.749Z,1591676105.749 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006292
CHAN A1 (24V): 0.000147
CHAN A2 (12V): -0.001939
CHAN A3 (5V): -0.001288
CHAN B0 (3.3V): -0.000127
CHAN B1 (3.15aV): -0.000237
CHAN B2 (3.15bV): -0.000117
CHAN B3 (GND): -0.000166
OPEN: -0.000342
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-09T04:15:47.977Z,1591676147.977 [SBIT](IMPORTANT): SBIT PASSED
2020-06-09T04:15:47.978Z,1591676147.978 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-06-09T04:15:47.983Z,1591676147.983 [SBIT](IMPORTANT): CBIT.gf24Offset=154 microampere;

2020-06-09T04:15:47.983Z,1591676147.983 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-06-09T04:15:47.983Z,1591676147.983 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=264.728883 cubic_centimeter;

2020-06-09T04:15:47.983Z,1591676147.983 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.226688 millimeter;

2020-06-09T04:15:48.381Z,1591676148.381 [MissionManager](IMPORTANT): Started mission Startup
2020-06-09T04:15:48.381Z,1591676148.381 [Startup] Running Loop=1
2020-06-09T04:15:48.381Z,1591676148.381 [Startup](DEBUG): Aggregate::initialize Startup
2020-06-09T04:15:48.381Z,1591676148.381 [Startup:A.GoToSurface] Running Loop=1
2020-06-09T04:15:48.381Z,1591676148.381 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-09T04:15:48.382Z,1591676148.382 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-09T04:15:48.382Z,1591676148.382 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-09T04:15:48.403Z,1591676148.403 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-09T04:15:48.403Z,1591676148.403 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-09T04:15:48.404Z,1591676148.404 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-09T04:15:48.405Z,1591676148.405 [Startup:StartupSatComms] Running Loop=1
2020-06-09T04:15:48.405Z,1591676148.405 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-06-09T04:15:48.405Z,1591676148.405 [Startup:StartupSatComms:A] Running Loop=1
2020-06-09T04:15:48.769Z,1591676148.769 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-06-09T04:16:38.443Z,1591676198.443 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-06-09T04:16:38.444Z,1591676198.444 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-09T04:16:38.453Z,1591676198.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-09T04:16:38.864Z,1591676198.864 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-09T04:16:38.864Z,1591676198.864 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-06-09T04:16:48.554Z,1591676208.554 [Startup:StartupSatComms:A](INFO): Timed out from 2020-06-09T04:15:48.4Z
2020-06-09T04:16:48.554Z,1591676208.554 [Startup:StartupSatComms:A] Stopped
2020-06-09T04:16:48.554Z,1591676208.554 [Startup:StartupSatComms:B] Running Loop=1
2020-06-09T04:16:48.941Z,1591676208.941 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-09T04:16:55.728Z,1591676215.728 [DataOverHttps](INFO): Sending 722 bytes from file Logs/20200608T174706/Express0071.lzma
2020-06-09T04:16:56.729Z,1591676216.729 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0071.lzma.bak
2020-06-09T04:16:56.729Z,1591676216.729 [DataOverHttps](INFO): SBD MOMSN=12373970
2020-06-09T04:17:12.332Z,1591676232.332 [DataOverHttps](INFO): Sending 732 bytes from file Logs/20200609T041327/Express0001.lzma
2020-06-09T04:17:13.333Z,1591676233.333 [DataOverHttps](INFO): Moved sent file to Logs/20200609T041327/Express0001.lzma.bak
2020-06-09T04:17:13.333Z,1591676233.333 [DataOverHttps](INFO): SBD MOMSN=12374032
2020-06-09T04:17:14.817Z,1591676234.817 [Startup:StartupSatComms:B] Stopped
2020-06-09T04:17:14.817Z,1591676234.817 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-06-09T04:17:14.817Z,1591676234.817 [Startup:StartupSatComms] Stopped
2020-06-09T04:17:14.817Z,1591676234.817 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-06-09T04:17:14.818Z,1591676234.818 [Startup](INFO): Completed Startup
2020-06-09T04:17:14.818Z,1591676234.818 [MissionManager](INFO): Startup is completed.
2020-06-09T04:17:14.818Z,1591676234.818 [MissionManager](INFO): Uninitializing Mission Startup
2020-06-09T04:17:14.818Z,1591676234.818 [Startup] Stopped
2020-06-09T04:17:14.818Z,1591676234.818 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-06-09T04:17:14.818Z,1591676234.818 [Startup:A.GoToSurface] Stopped
2020-06-09T04:17:14.818Z,1591676234.818 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-09T04:17:15.222Z,1591676235.222 [MissionManager](IMPORTANT): Started mission Default
2020-06-09T04:17:15.222Z,1591676235.222 [Default] Running Loop=1
2020-06-09T04:17:15.222Z,1591676235.222 [Default](DEBUG): Aggregate::initialize Default
2020-06-09T04:17:15.222Z,1591676235.222 [Default:B.GoToSurface] Running Loop=1
2020-06-09T04:17:15.222Z,1591676235.222 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-09T04:17:15.222Z,1591676235.222 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-09T04:17:15.223Z,1591676235.223 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-09T04:17:15.223Z,1591676235.223 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-09T04:17:15.223Z,1591676235.223 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-09T04:17:15.224Z,1591676235.224 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-09T04:17:15.224Z,1591676235.224 [Default:A.Wait] Running Loop=1
2020-06-09T04:17:15.224Z,1591676235.224 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-06-09T04:17:28.565Z,1591676248.565 [Default:A.Wait](INFO): Done Waiting.
2020-06-09T04:17:28.565Z,1591676248.565 [Default:A.Wait] Stopped
2020-06-09T04:17:28.565Z,1591676248.565 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T04:17:28.972Z,1591676248.972 [Default:CheckIn] Running Loop=1
2020-06-09T04:17:28.972Z,1591676248.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T04:17:28.972Z,1591676248.972 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T04:17:29.345Z,1591676249.345 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-06-09T04:18:05.573Z,1591676285.573 [CommandLine](IMPORTANT): got command show variable address
2020-06-09T04:18:05.576Z,1591676285.576 [CommandLine](IMPORTANT): acoustic_contact_address (unknown)
2020-06-09T04:18:05.645Z,1591676285.645 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count)
2020-06-09T04:18:05.645Z,1591676285.645 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum)
2020-06-09T04:18:05.646Z,1591676285.646 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum)
2020-06-09T04:18:05.649Z,1591676285.649 [CommandLine](IMPORTANT): DAT.localAddress (count)
2020-06-09T04:18:05.649Z,1591676285.649 [CommandLine](IMPORTANT): DAT.sbdAddress (enum)
2020-06-09T04:18:05.649Z,1591676285.649 [CommandLine](IMPORTANT): DAT.transponderAddress (enum)
2020-06-09T04:18:05.653Z,1591676285.653 [CommandLine](IMPORTANT): Micromodem.localAddress (enum)
2020-06-09T04:18:05.653Z,1591676285.653 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum)
2020-06-09T04:18:05.771Z,1591676285.771 [CommandLine](IMPORTANT): DAT.remoteAddress (enum)
2020-06-09T04:18:05.771Z,1591676285.771 [CommandLine](IMPORTANT): DAT.localAddressReading (enum)
2020-06-09T04:18:18.801Z,1591676298.801 [CommandLine](IMPORTANT): got command get DAT.localAddress
2020-06-09T04:18:18.801Z,1591676298.801 [CommandLine](IMPORTANT): DAT.localAddress 7 count
2020-06-09T04:19:07.514Z,1591676347.514 [DAT](INFO): Received command:set _.pressure 0.515843 atmosphere
2020-06-09T04:19:08.108Z,1591676348.108 [CommandLine](IMPORTANT): got command set _.pressure 0.515843 atmosphere
2020-06-09T04:19:08.114Z,1591676348.114 [DAT](INFO): Sending ack
2020-06-09T04:19:08.216Z,1591676348.216 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-06-09T04:19:08.584Z,1591676348.584 [DAT](INFO): setting remote address to 8
2020-06-09T04:19:08.995Z,1591676348.995 [DAT](DEBUG): checking for remote address setting acknowledgment
2020-06-09T04:19:08.996Z,1591676348.996 [DAT](INFO): set remote address to 8
2020-06-09T04:19:08.997Z,1591676348.997 [DAT](INFO): entering online mode
2020-06-09T04:19:09.403Z,1591676349.403 [DAT](DEBUG): checking for online mode acknowledgment
2020-06-09T04:19:09.415Z,1591676349.415 [DAT](INFO): online mode acknowledged
2020-06-09T04:19:09.415Z,1591676349.415 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-06-09T04:19:12.619Z,1591676352.619 [DAT](DEBUG): In parseResponses, sent ack so set commsState_ = SENDING_VERIFIED
2020-06-09T04:19:13.017Z,1591676353.017 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2020-06-09T04:19:19.884Z,1591676359.884 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-09T04:19:28.378Z,1591676368.378 [DAT](INFO): Received command:set _.pressure 0.515843 atmosphere
2020-06-09T04:19:28.977Z,1591676368.977 [CommandLine](IMPORTANT): got command set _.pressure 0.515843 atmosphere
2020-06-09T04:19:28.981Z,1591676368.981 [DAT](INFO): Sending ack
2020-06-09T04:19:39.636Z,1591676379.636 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-06-09T04:19:39.636Z,1591676379.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-09T04:19:39.646Z,1591676379.646 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-09T04:19:40.054Z,1591676380.054 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-09T04:19:40.054Z,1591676380.054 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-06-09T04:19:49.721Z,1591676389.721 [DAT](INFO): Received command:set _.pressure 0.515843 atmosphere
2020-06-09T04:19:50.849Z,1591676390.849 [CommandLine](IMPORTANT): got command set _.pressure 0.515843 atmosphere
2020-06-09T04:19:50.854Z,1591676390.854 [DAT](INFO): Sending ack
2020-06-09T04:20:05.788Z,1591676405.788 [CommandLine](IMPORTANT): got command quit
2020-06-09T04:20:06.795Z,1591676406.795 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-06-09T04:20:06.795Z,1591676406.795 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:06.796Z,1591676406.796 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:06.987Z,1591676406.987 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-06-09T04:20:06.987Z,1591676406.987 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:06.988Z,1591676406.988 [CommandLine](INFO): Join timeout helper Thread ID is 8781
2020-06-09T04:20:06.988Z,1591676406.988 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-06-09T04:20:06.988Z,1591676406.988 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:06.989Z,1591676406.989 [NavChartDb](INFO): Join timeout helper Thread ID is 8782
2020-06-09T04:20:07.147Z,1591676407.147 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:07.147Z,1591676407.147 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:07.151Z,1591676407.151 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-06-09T04:20:07.151Z,1591676407.151 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:07.151Z,1591676407.151 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8783
2020-06-09T04:20:07.535Z,1591676407.535 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:07.535Z,1591676407.535 [WetLabsBB2FL](INFO): Powering down
2020-06-09T04:20:07.536Z,1591676407.536 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:07.563Z,1591676407.563 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-06-09T04:20:07.563Z,1591676407.563 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:07.563Z,1591676407.563 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8784
2020-06-09T04:20:07.911Z,1591676407.911 [CTD_Seabird](INFO): Powering down
2020-06-09T04:20:07.923Z,1591676407.923 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:07.923Z,1591676407.923 [CTD_Seabird](INFO): Powering down
2020-06-09T04:20:07.935Z,1591676407.935 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:07.935Z,1591676407.935 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-06-09T04:20:07.935Z,1591676407.935 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:07.936Z,1591676407.936 [Radio_Surface](INFO): Join timeout helper Thread ID is 8785
2020-06-09T04:20:08.223Z,1591676408.223 [Radio_Surface](INFO): Powering down
2020-06-09T04:20:08.224Z,1591676408.224 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:08.224Z,1591676408.224 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:08.236Z,1591676408.236 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-06-09T04:20:08.236Z,1591676408.236 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:08.236Z,1591676408.236 [Onboard](INFO): Join timeout helper Thread ID is 8786
2020-06-09T04:20:08.347Z,1591676408.347 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-06-09T04:20:08.543Z,1591676408.543 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:08.543Z,1591676408.543 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:08.566Z,1591676408.566 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-06-09T04:20:08.567Z,1591676408.567 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:08.568Z,1591676408.568 [DataOverHttps](INFO): Join timeout helper Thread ID is 8787
2020-06-09T04:20:08.796Z,1591676408.796 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2305760 %. Average:283984.625000
2020-06-09T04:20:09.351Z,1591676409.351 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:09.351Z,1591676409.351 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.375Z,1591676409.375 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-06-09T04:20:09.375Z,1591676409.375 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.376Z,1591676409.376 [logger](INFO): Join timeout helper Thread ID is 8788
2020-06-09T04:20:09.423Z,1591676409.423 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:09.423Z,1591676409.423 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.435Z,1591676409.435 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-06-09T04:20:09.436Z,1591676409.436 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.436Z,1591676409.436 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-06-09T04:20:09.436Z,1591676409.436 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.436Z,1591676409.436 [controlThread](INFO): Join timeout helper Thread ID is 8789
2020-06-09T04:20:09.495Z,1591676409.495 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T04:20:09.495Z,1591676409.495 [controlThread](DEBUG): Uninitializing ControlThread
2020-06-09T04:20:09.496Z,1591676409.496 [AHRS_M2](INFO): Powering down
2020-06-09T04:20:09.568Z,1591676409.568 [NAL9602](INFO): Powering down
2020-06-09T04:20:09.569Z,1591676409.569 [DAT](INFO): Powering down
2020-06-09T04:20:09.689Z,1591676409.689 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-06-09T04:20:09.690Z,1591676409.690 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-06-09T04:20:09.690Z,1591676409.690 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-06-09T04:20:09.691Z,1591676409.691 [MissionManager](INFO): Uninitializing Mission Default
2020-06-09T04:20:09.691Z,1591676409.691 [Default] Stopped
2020-06-09T04:20:09.691Z,1591676409.691 [Default](DEBUG): Aggregate::uninitialize Default
2020-06-09T04:20:09.691Z,1591676409.691 [Default:B.GoToSurface] Stopped
2020-06-09T04:20:09.691Z,1591676409.691 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-09T04:20:09.691Z,1591676409.691 [Default:CheckIn] Stopped
2020-06-09T04:20:09.691Z,1591676409.691 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T04:20:09.691Z,1591676409.691 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T04:20:09.694Z,1591676409.694 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-06-09T04:20:09.694Z,1591676409.694 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-06-09T04:20:09.694Z,1591676409.694 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-06-09T04:20:09.694Z,1591676409.694 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-06-09T04:20:09.695Z,1591676409.695 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-06-09T04:20:09.695Z,1591676409.695 [BuoyancyServo](INFO): Powering down
2020-06-09T04:20:09.707Z,1591676409.707 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-06-09T04:20:09.707Z,1591676409.707 [ElevatorServo](INFO): Powering down
2020-06-09T04:20:09.708Z,1591676409.708 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-06-09T04:20:09.708Z,1591676409.708 [MassServo](INFO): Powering down
2020-06-09T04:20:09.709Z,1591676409.709 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-09T04:20:09.709Z,1591676409.709 [RudderServo](INFO): Powering down
2020-06-09T04:20:09.709Z,1591676409.709 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-06-09T04:20:09.709Z,1591676409.709 [ThrusterServo](INFO): Powering down
2020-06-09T04:20:09.710Z,1591676409.710 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-06-09T04:20:09.710Z,1591676409.710 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-06-09T04:20:09.711Z,1591676409.711 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-06-09T04:20:09.711Z,1591676409.711 [CBIT](DEBUG): Powering off loads.
2020-06-09T04:20:09.723Z,1591676409.723 [CBIT](DEBUG): Disabling WDT.
2020-06-09T04:20:09.735Z,1591676409.735 [CBIT](DEBUG): Opening all GF detection circuits.
2020-06-09T04:20:09.735Z,1591676409.735 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.816Z,1591676409.816 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.818Z,1591676409.818 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.823Z,1591676409.823 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.856Z,1591676409.856 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.860Z,1591676409.860 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.904Z,1591676409.904 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-09T04:20:09.966Z,1591676409.966 [logger ThreadHandler](INFO): Thread cancelled.