2020-12-01T17:09:08.574Z,1606842548.574 [Supervisor](DEBUG): Initializing supervisor.
2020-12-01T17:09:08.577Z,1606842548.577 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-12-01T17:09:08.577Z,1606842548.577 [SyncHandler](INFO): Protected caller Thread ID is 5872
2020-12-01T17:09:08.577Z,1606842548.577 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-12-01T17:09:08.578Z,1606842548.578 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-12-01T17:09:08.579Z,1606842548.579 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5873
2020-12-01T17:09:08.582Z,1606842548.582 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-12-01T17:09:08.598Z,1606842548.598 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-12-01T17:09:08.599Z,1606842548.599 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-12-01T17:09:08.600Z,1606842548.600 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5874
2020-12-01T17:09:08.600Z,1606842548.600 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-12-01T17:09:08.601Z,1606842548.601 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-12-01T17:09:08.601Z,1606842548.601 [logger ThreadHandler](INFO): Protected caller Thread ID is 5875
2020-12-01T17:09:08.604Z,1606842548.604 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-12-01T17:09:08.604Z,1606842548.604 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-12-01T17:09:08.606Z,1606842548.606 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-12-01T17:09:08.697Z,1606842548.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-12-01T17:09:08.698Z,1606842548.698 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-12-01T17:09:09.259Z,1606842549.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-12-01T17:09:09.260Z,1606842549.260 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-12-01T17:09:09.452Z,1606842549.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-12-01T17:09:09.452Z,1606842549.452 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-12-01T17:09:09.547Z,1606842549.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-12-01T17:09:09.548Z,1606842549.548 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-12-01T17:09:09.648Z,1606842549.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-12-01T17:09:09.649Z,1606842549.649 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-12-01T17:09:09.727Z,1606842549.727 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-12-01T17:09:09.861Z,1606842549.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-12-01T17:09:09.862Z,1606842549.862 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-12-01T17:09:10.144Z,1606842550.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-12-01T17:09:10.144Z,1606842550.144 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-12-01T17:09:10.625Z,1606842550.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-12-01T17:09:10.626Z,1606842550.626 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-12-01T17:09:10.764Z,1606842550.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-12-01T17:09:10.765Z,1606842550.765 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-12-01T17:09:10.944Z,1606842550.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-12-01T17:09:10.945Z,1606842550.945 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-12-01T17:09:11.412Z,1606842551.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-12-01T17:09:11.413Z,1606842551.413 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-12-01T17:09:11.620Z,1606842551.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-12-01T17:09:11.620Z,1606842551.620 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-12-01T17:09:11.812Z,1606842551.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-12-01T17:09:11.812Z,1606842551.812 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-12-01T17:09:12.207Z,1606842552.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-12-01T17:09:12.207Z,1606842552.207 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-12-01T17:09:12.517Z,1606842552.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-12-01T17:09:12.518Z,1606842552.518 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-12-01T17:09:12.519Z,1606842552.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-12-01T17:09:12.600Z,1606842552.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-12-01T17:09:12.747Z,1606842552.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-12-01T17:09:12.851Z,1606842552.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-12-01T17:09:12.933Z,1606842552.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-12-01T17:09:13.024Z,1606842553.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-12-01T17:09:13.210Z,1606842553.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-12-01T17:09:13.454Z,1606842553.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-12-01T17:09:13.455Z,1606842553.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-12-01T17:09:13.554Z,1606842553.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-12-01T17:09:13.794Z,1606842553.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-12-01T17:09:14.012Z,1606842554.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-12-01T17:09:14.109Z,1606842554.109 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-12-01T17:09:14.124Z,1606842554.124 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-12-01T17:09:14.253Z,1606842554.253 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-12-01T17:09:14.254Z,1606842554.254 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-12-01T17:09:14.350Z,1606842554.350 [VerticalControl](DEBUG): Construct VerticalControl.
2020-12-01T17:09:14.406Z,1606842554.406 [VerticalControl] Loaded
2020-12-01T17:09:14.406Z,1606842554.406 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-12-01T17:09:14.407Z,1606842554.407 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-12-01T17:09:14.442Z,1606842554.442 [HorizontalControl] Loaded
2020-12-01T17:09:14.443Z,1606842554.443 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-12-01T17:09:14.443Z,1606842554.443 [SpeedControl](DEBUG): Construct SpeedControl.
2020-12-01T17:09:14.446Z,1606842554.446 [SpeedControl] Loaded
2020-12-01T17:09:14.447Z,1606842554.447 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-12-01T17:09:14.447Z,1606842554.447 [LoopControl](DEBUG): Construct LoopControl.
2020-12-01T17:09:14.448Z,1606842554.448 [LoopControl] Loaded
2020-12-01T17:09:14.448Z,1606842554.448 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-12-01T17:09:14.448Z,1606842554.448 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-12-01T17:09:14.449Z,1606842554.449 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-12-01T17:09:14.486Z,1606842554.486 [DepthRateCalculator] Loaded
2020-12-01T17:09:14.487Z,1606842554.487 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-12-01T17:09:14.489Z,1606842554.489 [PitchRateCalculator] Loaded
2020-12-01T17:09:14.489Z,1606842554.489 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-12-01T17:09:14.497Z,1606842554.497 [SpeedCalculator] Loaded
2020-12-01T17:09:14.497Z,1606842554.497 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-12-01T17:09:14.508Z,1606842554.508 [TempGradientCalculator] Loaded
2020-12-01T17:09:14.509Z,1606842554.509 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-12-01T17:09:14.511Z,1606842554.511 [YawRateCalculator] Loaded
2020-12-01T17:09:14.511Z,1606842554.511 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-12-01T17:09:14.525Z,1606842554.525 [ElevatorOffsetCalculator] Loaded
2020-12-01T17:09:14.526Z,1606842554.526 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-12-01T17:09:14.526Z,1606842554.526 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-12-01T17:09:14.527Z,1606842554.527 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-12-01T17:09:14.552Z,1606842554.552 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-12-01T17:09:14.553Z,1606842554.553 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-12-01T17:09:14.634Z,1606842554.634 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-12-01T17:09:14.634Z,1606842554.634 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-12-01T17:09:14.909Z,1606842554.909 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-12-01T17:09:14.909Z,1606842554.909 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-12-01T17:09:15.002Z,1606842555.002 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-12-01T17:09:15.003Z,1606842555.003 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-12-01T17:09:15.614Z,1606842555.614 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-12-01T17:09:15.616Z,1606842555.616 [AHRS_M2](INFO): created writer for : platform_orientation
2020-12-01T17:09:15.618Z,1606842555.618 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-12-01T17:09:15.621Z,1606842555.621 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-12-01T17:09:15.621Z,1606842555.621 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-12-01T17:09:15.624Z,1606842555.624 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-12-01T17:09:15.624Z,1606842555.624 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-12-01T17:09:15.627Z,1606842555.627 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-12-01T17:09:15.663Z,1606842555.663 [AHRS_M2] Loaded
2020-12-01T17:09:15.663Z,1606842555.663 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-12-01T17:09:15.733Z,1606842555.733 [DataOverHttps] Loaded
2020-12-01T17:09:15.733Z,1606842555.733 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-12-01T17:09:15.734Z,1606842555.734 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0
2020-12-01T17:09:15.735Z,1606842555.735 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5957
2020-12-01T17:09:15.751Z,1606842555.751 [Depth_Keller] Loaded
2020-12-01T17:09:15.751Z,1606842555.751 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-12-01T17:09:15.754Z,1606842555.754 [DropWeight] Loaded
2020-12-01T17:09:15.754Z,1606842555.754 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-12-01T17:09:15.807Z,1606842555.807 [NAL9602] Loaded
2020-12-01T17:09:15.807Z,1606842555.807 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-12-01T17:09:15.832Z,1606842555.832 [Onboard] Loaded
2020-12-01T17:09:15.832Z,1606842555.832 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-12-01T17:09:15.833Z,1606842555.833 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0
2020-12-01T17:09:15.834Z,1606842555.834 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5958
2020-12-01T17:09:15.844Z,1606842555.844 [Radio_Surface] Loaded
2020-12-01T17:09:15.844Z,1606842555.844 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-12-01T17:09:15.845Z,1606842555.845 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0
2020-12-01T17:09:15.845Z,1606842555.845 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5959
2020-12-01T17:09:15.871Z,1606842555.871 [RDI_Pathfinder] Loaded
2020-12-01T17:09:15.872Z,1606842555.872 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-12-01T17:09:15.942Z,1606842555.942 [DAT] Loaded
2020-12-01T17:09:15.942Z,1606842555.942 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-12-01T17:09:16.707Z,1606842556.707 [BPC1] Loaded
2020-12-01T17:09:16.707Z,1606842556.707 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-12-01T17:09:16.708Z,1606842556.708 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-12-01T17:09:16.708Z,1606842556.708 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-12-01T17:09:16.784Z,1606842556.784 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-12-01T17:09:16.784Z,1606842556.784 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-12-01T17:09:16.795Z,1606842556.795 [NavChart] Loaded
2020-12-01T17:09:16.795Z,1606842556.795 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-12-01T17:09:16.799Z,1606842556.799 [UniversalFixResidualReporter] Loaded
2020-12-01T17:09:16.799Z,1606842556.799 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-12-01T17:09:16.799Z,1606842556.799 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-12-01T17:09:16.800Z,1606842556.800 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-12-01T17:09:16.925Z,1606842556.925 [SBIT](DEBUG): Construct Startup Built In Test.
2020-12-01T17:09:16.934Z,1606842556.934 [SBIT] Loaded
2020-12-01T17:09:16.934Z,1606842556.934 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-12-01T17:09:16.935Z,1606842556.935 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-12-01T17:09:16.947Z,1606842556.947 [IBIT] Loaded
2020-12-01T17:09:16.947Z,1606842556.947 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-12-01T17:09:16.950Z,1606842556.950 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-12-01T17:09:17.043Z,1606842557.043 [CBIT] Loaded
2020-12-01T17:09:17.044Z,1606842557.044 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-12-01T17:09:17.044Z,1606842557.044 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-12-01T17:09:17.045Z,1606842557.045 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-12-01T17:09:17.151Z,1606842557.151 [BuoyancyServo] Loaded
2020-12-01T17:09:17.152Z,1606842557.152 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-12-01T17:09:17.168Z,1606842557.168 [ElevatorServo] Loaded
2020-12-01T17:09:17.168Z,1606842557.168 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-12-01T17:09:17.184Z,1606842557.184 [MassServo] Loaded
2020-12-01T17:09:17.184Z,1606842557.184 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-12-01T17:09:17.199Z,1606842557.199 [RudderServo] Loaded
2020-12-01T17:09:17.199Z,1606842557.199 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-12-01T17:09:17.214Z,1606842557.214 [ThrusterServo] Loaded
2020-12-01T17:09:17.214Z,1606842557.214 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-12-01T17:09:17.215Z,1606842557.215 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-12-01T17:09:17.215Z,1606842557.215 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-12-01T17:09:17.233Z,1606842557.233 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-12-01T17:09:17.233Z,1606842557.233 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-12-01T17:09:17.436Z,1606842557.436 [BackSeatDriver] Loaded
2020-12-01T17:09:17.436Z,1606842557.436 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-12-01T17:09:17.437Z,1606842557.437 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B074E0
2020-12-01T17:09:17.438Z,1606842557.438 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 5960
2020-12-01T17:09:17.457Z,1606842557.457 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-12-01T17:09:17.460Z,1606842557.460 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-12-01T17:09:17.460Z,1606842557.460 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-12-01T17:09:17.463Z,1606842557.463 [CTD_Seabird](INFO): created writer for : depth
2020-12-01T17:09:17.463Z,1606842557.463 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-12-01T17:09:17.466Z,1606842557.466 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-12-01T17:09:17.466Z,1606842557.466 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-12-01T17:09:17.469Z,1606842557.469 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-12-01T17:09:17.470Z,1606842557.470 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-12-01T17:09:17.473Z,1606842557.473 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-12-01T17:09:17.473Z,1606842557.473 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-12-01T17:09:17.476Z,1606842557.476 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-12-01T17:09:17.477Z,1606842557.477 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-12-01T17:09:17.480Z,1606842557.480 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-12-01T17:09:17.480Z,1606842557.480 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-12-01T17:09:17.483Z,1606842557.483 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-12-01T17:09:17.484Z,1606842557.484 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-12-01T17:09:17.484Z,1606842557.484 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-12-01T17:09:17.500Z,1606842557.500 [CTD_Seabird] Loaded
2020-12-01T17:09:17.500Z,1606842557.500 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-12-01T17:09:17.501Z,1606842557.501 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B374E0
2020-12-01T17:09:17.501Z,1606842557.501 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5961
2020-12-01T17:09:17.518Z,1606842557.518 [PAR_Licor] Loaded
2020-12-01T17:09:17.519Z,1606842557.519 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-12-01T17:09:17.531Z,1606842557.531 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-12-01T17:09:17.531Z,1606842557.531 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-12-01T17:09:17.533Z,1606842557.533 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-12-01T17:09:17.533Z,1606842557.533 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-12-01T17:09:17.535Z,1606842557.535 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-12-01T17:09:17.535Z,1606842557.535 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-12-01T17:09:17.537Z,1606842557.537 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-12-01T17:09:17.537Z,1606842557.537 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-12-01T17:09:17.539Z,1606842557.539 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-12-01T17:09:17.539Z,1606842557.539 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-12-01T17:09:17.541Z,1606842557.541 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-12-01T17:09:17.541Z,1606842557.541 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-12-01T17:09:17.543Z,1606842557.543 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-12-01T17:09:17.543Z,1606842557.543 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-12-01T17:09:17.545Z,1606842557.545 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.547Z,1606842557.547 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.548Z,1606842557.548 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.548Z,1606842557.548 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.550Z,1606842557.550 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.550Z,1606842557.550 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.552Z,1606842557.552 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.552Z,1606842557.552 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:09:17.554Z,1606842557.554 [WetLabsBB2FL] Loaded
2020-12-01T17:09:17.554Z,1606842557.554 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-12-01T17:09:17.555Z,1606842557.555 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2020-12-01T17:09:17.556Z,1606842557.556 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5962
2020-12-01T17:09:17.575Z,1606842557.575 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-12-01T17:09:17.576Z,1606842557.576 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-12-01T17:09:17.576Z,1606842557.576 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-12-01T17:09:17.581Z,1606842557.581 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_:
2020-12-01T17:09:17.581Z,1606842557.581 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-12-01T17:09:17.581Z,1606842557.581 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-12-01T17:09:17.583Z,1606842557.583 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-12-01T17:09:17.583Z,1606842557.583 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-12-01T17:09:17.586Z,1606842557.586 [WetLabsUBAT] Loaded
2020-12-01T17:09:17.586Z,1606842557.586 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-12-01T17:09:17.587Z,1606842557.587 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B974E0
2020-12-01T17:09:17.588Z,1606842557.588 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 5963
2020-12-01T17:09:17.588Z,1606842557.588 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-12-01T17:09:17.592Z,1606842557.592 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-12-01T17:09:17.593Z,1606842557.593 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-12-01T17:09:17.601Z,1606842557.601 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-12-01T17:09:17.602Z,1606842557.602 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC74E0
2020-12-01T17:09:17.602Z,1606842557.602 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5964
2020-12-01T17:09:17.607Z,1606842557.607 [Supervisor](INFO): Main Thread ID is 5457
2020-12-01T17:09:17.607Z,1606842557.607 [Supervisor](DEBUG): Running supervisor.
2020-12-01T17:09:17.608Z,1606842557.608 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5965
2020-12-01T17:09:17.610Z,1606842557.610 [controlThread ThreadHandler](INFO): Handler Thread ID is 5966
2020-12-01T17:09:17.611Z,1606842557.611 [controlThread](DEBUG): Initializing ControlThread
2020-12-01T17:09:17.611Z,1606842557.611 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-12-01T17:09:17.613Z,1606842557.613 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-12-01T17:09:17.614Z,1606842557.614 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-12-01T17:09:17.614Z,1606842557.614 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-12-01T17:09:17.615Z,1606842557.615 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-12-01T17:09:17.615Z,1606842557.615 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-12-01T17:09:17.615Z,1606842557.615 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-12-01T17:09:17.616Z,1606842557.616 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-12-01T17:09:17.616Z,1606842557.616 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-12-01T17:09:17.616Z,1606842557.616 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-12-01T17:09:17.622Z,1606842557.622 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-12-01T17:09:17.623Z,1606842557.623 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-12-01T17:09:17.623Z,1606842557.623 [SBIT](INFO): Initialize SBIT Component.
2020-12-01T17:09:17.624Z,1606842557.624 [SBIT](IMPORTANT): git: 2020-11-24
2020-12-01T17:09:17.624Z,1606842557.624 [SBIT](INFO): git hash: adde971cea593344b7c42ced3277a520a74b3766
2020-12-01T17:09:17.624Z,1606842557.624 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-12-01T17:09:17.625Z,1606842557.625 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-12-01T17:09:17.626Z,1606842557.626 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2020-12-01T17:09:17.627Z,1606842557.627 [IBIT](INFO): Initialize IBIT Component.
2020-12-01T17:09:17.627Z,1606842557.627 [CBIT](DEBUG): Initialize CBIT Component.
2020-12-01T17:09:17.628Z,1606842557.628 [logger ThreadHandler](INFO): Handler Thread ID is 5967
2020-12-01T17:09:17.640Z,1606842557.640 [CBIT](DEBUG): Initialized mux pins.
2020-12-01T17:09:17.640Z,1606842557.640 [CBIT](DEBUG): Initializing the watchdog timer.
2020-12-01T17:09:17.648Z,1606842557.648 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5968
2020-12-01T17:09:17.649Z,1606842557.649 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-12-01T17:09:17.660Z,1606842557.660 [Onboard ThreadHandler](INFO): Handler Thread ID is 5969
2020-12-01T17:09:17.664Z,1606842557.664 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-12-01T17:09:17.665Z,1606842557.665 [CBIT](DEBUG): Initializing heartbeat.
2020-12-01T17:09:17.678Z,1606842557.678 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5970
2020-12-01T17:09:17.700Z,1606842557.700 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 5971
2020-12-01T17:09:17.720Z,1606842557.720 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5972
2020-12-01T17:09:17.721Z,1606842557.721 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-12-01T17:09:17.725Z,1606842557.725 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5974
2020-12-01T17:09:17.726Z,1606842557.726 [WetLabsBB2FL](INFO): Powering down
2020-12-01T17:09:17.736Z,1606842557.736 [CBIT](DEBUG): Deactivating GF circuits.
2020-12-01T17:09:17.736Z,1606842557.736 [CBIT](DEBUG): Deactivating emergency mode.
2020-12-01T17:09:17.737Z,1606842557.737 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 5975
2020-12-01T17:09:17.760Z,1606842557.760 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5976
2020-12-01T17:09:17.763Z,1606842557.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-12-01T17:09:17.763Z,1606842557.763 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-12-01T17:09:17.763Z,1606842557.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-12-01T17:09:17.764Z,1606842557.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-12-01T17:09:17.764Z,1606842557.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-12-01T17:09:17.764Z,1606842557.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-12-01T17:09:17.764Z,1606842557.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-12-01T17:09:17.764Z,1606842557.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-12-01T17:09:17.765Z,1606842557.765 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-12-01T17:09:17.765Z,1606842557.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-12-01T17:09:17.765Z,1606842557.765 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-12-01T17:09:17.765Z,1606842557.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-12-01T17:09:17.765Z,1606842557.765 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-12-01T17:09:17.765Z,1606842557.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-12-01T17:09:17.766Z,1606842557.766 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-12-01T17:09:17.766Z,1606842557.766 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-12-01T17:09:17.772Z,1606842557.772 [CBIT](DEBUG): Backplane powered.
2020-12-01T17:09:17.777Z,1606842557.777 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-12-01T17:09:17.795Z,1606842557.795 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-01T17:09:17.833Z,1606842557.833 [MissionManager](DEBUG):
2020-12-01T17:09:17.834Z,1606842557.834 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-12-01T17:09:17.915Z,1606842557.915 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-12-01T17:09:17.932Z,1606842557.932 [Default:A.Wait](DEBUG): Construct Wait.
2020-12-01T17:09:17.934Z,1606842557.934 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-01T17:09:17.953Z,1606842557.953 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-12-01T17:09:17.978Z,1606842557.978 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-12-01T17:09:17.983Z,1606842557.983 [Default:E.Execute](DEBUG): Construct Execute.
2020-12-01T17:09:18.007Z,1606842558.007 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2020-12-01T17:09:18.011Z,1606842558.011 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-12-01T17:09:18.024Z,1606842558.024 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-12-01T17:09:18.080Z,1606842558.080 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-12-01T17:09:18.084Z,1606842558.084 [Radio_Surface](INFO): Powering up
2020-12-01T17:09:18.091Z,1606842558.091 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-12-01T17:09:18.091Z,1606842558.091 [DAT](INFO): Powering up
2020-12-01T17:09:18.091Z,1606842558.091 [DAT](DEBUG): Initializing DAT.
2020-12-01T17:09:18.109Z,1606842558.109 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-12-01T17:09:18.258Z,1606842558.258 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-12-01T17:09:18.286Z,1606842558.286 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:09:18.292Z,1606842558.292 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-12-01T17:09:18.293Z,1606842558.293 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:09:18.300Z,1606842558.300 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-12-01T17:09:18.301Z,1606842558.301 [MassServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:09:18.309Z,1606842558.309 [MassServo](DEBUG): Initializing MassServo.
2020-12-01T17:09:18.310Z,1606842558.310 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:09:18.316Z,1606842558.316 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-01T17:09:18.317Z,1606842558.317 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:09:18.324Z,1606842558.324 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-12-01T17:09:18.444Z,1606842558.444 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2020-12-01T17:09:18.445Z,1606842558.445 [DropWeight] Hardware Fault, FailCount= 1
2020-12-01T17:09:18.445Z,1606842558.445 [DropWeight](ERROR): Hardware Fault
2020-12-01T17:09:18.461Z,1606842558.461 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-12-01T17:09:18.485Z,1606842558.485 [CommandLine](FAULT): Scheduling is paused
2020-12-01T17:09:18.486Z,1606842558.486 [CBIT](INFO): Critical error at 20201201T170918
2020-12-01T17:09:18.486Z,1606842558.486 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-12-01T17:09:18.496Z,1606842558.496 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-12-01T17:09:18.497Z,1606842558.497 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-12-01T17:09:19.184Z,1606842559.184 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-12-01T17:09:19.184Z,1606842559.184 [RudderServo](FAULT): Rudder failed to initialize
2020-12-01T17:09:19.184Z,1606842559.184 [RudderServo] Communications Fault, FailCount= 1
2020-12-01T17:09:19.184Z,1606842559.184 [RudderServo](ERROR): Communications Fault
2020-12-01T17:09:19.282Z,1606842559.282 [CBIT](INFO): Critical error at 20201201T170918
2020-12-01T17:09:19.285Z,1606842559.285 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-12-01T17:09:19.453Z,1606842559.453 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-01T17:09:19.453Z,1606842559.453 [RudderServo](INFO): Powering down
2020-12-01T17:09:20.115Z,1606842560.115 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:09:20.233Z,1606842560.233 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-01T17:09:20.237Z,1606842560.237 [CBIT](INFO): Clearing failed state for component RudderServo
2020-12-01T17:09:20.237Z,1606842560.237 [RudderServo] No Fault, FailCount= 1
2020-12-01T17:09:32.228Z,1606842572.228 [DAT](INFO): commRate: 800
2020-12-01T17:09:34.259Z,1606842574.259 [DAT](INFO): entering command mode
2020-12-01T17:09:34.650Z,1606842574.650 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:35.054Z,1606842575.054 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:35.465Z,1606842575.465 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:35.873Z,1606842575.873 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:36.270Z,1606842576.270 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:36.681Z,1606842576.681 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:37.074Z,1606842577.074 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:37.486Z,1606842577.486 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:37.909Z,1606842577.909 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:38.290Z,1606842578.290 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:38.701Z,1606842578.701 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:39.097Z,1606842579.097 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:39.494Z,1606842579.494 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:39.937Z,1606842579.937 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:40.302Z,1606842580.302 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:40.722Z,1606842580.722 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:41.110Z,1606842581.110 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:41.514Z,1606842581.514 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:41.941Z,1606842581.941 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:42.326Z,1606842582.326 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:42.726Z,1606842582.726 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:43.144Z,1606842583.144 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:43.538Z,1606842583.538 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:43.946Z,1606842583.946 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:44.372Z,1606842584.372 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:44.778Z,1606842584.778 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:45.150Z,1606842585.150 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:45.574Z,1606842585.574 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:45.963Z,1606842585.963 [NAL9602](INFO): Powering up NAL9602
2020-12-01T17:09:45.980Z,1606842585.980 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:46.362Z,1606842586.362 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:46.781Z,1606842586.781 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:47.170Z,1606842587.170 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:47.612Z,1606842587.612 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:47.982Z,1606842587.982 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:48.394Z,1606842588.394 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:48.797Z,1606842588.797 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:49.194Z,1606842589.194 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:49.603Z,1606842589.603 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:09:49.604Z,1606842589.604 [DAT](FAULT): failed to enter command mode
2020-12-01T17:09:50.009Z,1606842590.009 [DAT](INFO): entering command mode
2020-12-01T17:09:50.403Z,1606842590.403 [DAT](INFO): setting verbose to 3
2020-12-01T17:09:50.822Z,1606842590.822 [DAT](INFO): set verbose to 3
2020-12-01T17:09:50.822Z,1606842590.822 [DAT](INFO): setting DatVerbose to 27440
2020-12-01T17:09:51.218Z,1606842591.218 [DAT](INFO): set DatVerbose to 27440
2020-12-01T17:09:51.218Z,1606842591.218 [DAT](INFO): setting transmit power to 8
2020-12-01T17:09:51.615Z,1606842591.615 [DAT](INFO): set transmit power to 8
2020-12-01T17:09:51.615Z,1606842591.615 [DAT](INFO): setting local address to 9
2020-12-01T17:09:52.023Z,1606842592.023 [DAT](INFO): set local address to 9
2020-12-01T17:09:56.865Z,1606842596.865 [NAL9602](INFO): NAL9602 initialized
2020-12-01T17:10:07.387Z,1606842607.387 [SBIT](IMPORTANT): Beginning Startup BIT
2020-12-01T17:10:07.391Z,1606842607.391 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-01T17:10:18.336Z,1606842618.336 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011431
CHAN A1 (24V): -0.002182
CHAN A2 (12V): -0.007171
CHAN A3 (5V): -0.002607
CHAN B0 (3.3V): -0.000110
CHAN B1 (3.15aV): -0.000005
CHAN B2 (3.15bV): -0.000790
CHAN B3 (GND): 0.002925
OPEN: 0.005174
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-01T17:11:01.126Z,1606842661.126 [SBIT](IMPORTANT): SBIT PASSED
2020-12-01T17:11:01.126Z,1606842661.126 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-12-01T17:11:01.127Z,1606842661.127 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2020-12-01T17:11:01.127Z,1606842661.127 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;
2020-12-01T17:11:01.127Z,1606842661.127 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;
2020-12-01T17:11:01.127Z,1606842661.127 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;
2020-12-01T17:11:01.127Z,1606842661.127 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;
2020-12-01T17:11:01.127Z,1606842661.127 [SBIT](IMPORTANT): DAT.verbosity=0 count;
2020-12-01T17:11:01.127Z,1606842661.127 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;
2020-12-01T17:11:01.128Z,1606842661.128 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;
2020-12-01T17:11:01.128Z,1606842661.128 [SBIT](IMPORTANT): PAR_Licor.parCal=1.01146 none;
2020-12-01T17:11:01.128Z,1606842661.128 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;
2020-12-01T17:11:01.128Z,1606842661.128 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=254.6 cubic_centimeter;
2020-12-01T17:11:01.128Z,1606842661.128 [SBIT](IMPORTANT): VerticalControl.massDefault=7.48 millimeter;
2020-12-01T17:11:01.128Z,1606842661.128 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;
2020-12-01T17:11:01.546Z,1606842661.546 [MissionManager](IMPORTANT): Started mission Startup
2020-12-01T17:11:01.546Z,1606842661.546 [Startup] Running Loop=1
2020-12-01T17:11:01.547Z,1606842661.547 [Startup](DEBUG): Aggregate::initialize Startup
2020-12-01T17:11:01.547Z,1606842661.547 [Startup:A.GoToSurface] Running Loop=1
2020-12-01T17:11:01.547Z,1606842661.547 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-01T17:11:01.547Z,1606842661.547 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-01T17:11:01.548Z,1606842661.548 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-01T17:11:01.548Z,1606842661.548 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-01T17:11:01.549Z,1606842661.549 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-01T17:11:01.549Z,1606842661.549 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-01T17:11:01.550Z,1606842661.550 [Startup:StartupSatComms] Running Loop=1
2020-12-01T17:11:01.550Z,1606842661.550 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-12-01T17:11:01.551Z,1606842661.551 [Startup:StartupSatComms:A] Running Loop=1
2020-12-01T17:11:01.974Z,1606842661.974 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-12-01T17:12:01.749Z,1606842721.749 [Startup:StartupSatComms:A](INFO): Timed out from 2020-12-01T17:11:01.6Z
2020-12-01T17:12:01.749Z,1606842721.749 [Startup:StartupSatComms:A] Stopped
2020-12-01T17:12:01.749Z,1606842721.749 [Startup:StartupSatComms:B] Running Loop=1
2020-12-01T17:12:02.123Z,1606842722.123 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-01T17:12:17.880Z,1606842737.880 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-12-01T17:12:17.880Z,1606842737.880 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T17:12:17.906Z,1606842737.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T17:12:18.294Z,1606842738.294 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T17:12:18.294Z,1606842738.294 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-12-01T17:12:40.862Z,1606842760.862 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004611
2020-12-01T17:12:55.199Z,1606842775.199 [CommandLine](IMPORTANT): got command failComponent
2020-12-01T17:12:55.202Z,1606842775.202 [CommandLine](IMPORTANT): Failed components:
2020-12-01T17:12:55.203Z,1606842775.203 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-12-01T17:13:01.983Z,1606842781.983 [Startup:StartupSatComms:B](INFO): Timed out from 2020-12-01T17:12:01.7Z
2020-12-01T17:13:01.983Z,1606842781.983 [Startup:StartupSatComms:B] Stopped
2020-12-01T17:13:01.983Z,1606842781.983 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-12-01T17:13:01.983Z,1606842781.983 [Startup:StartupSatComms] Stopped
2020-12-01T17:13:01.983Z,1606842781.983 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-12-01T17:13:01.984Z,1606842781.984 [Startup](INFO): Completed Startup
2020-12-01T17:13:01.984Z,1606842781.984 [MissionManager](INFO): Startup is completed.
2020-12-01T17:13:01.984Z,1606842781.984 [MissionManager](INFO): Uninitializing Mission Startup
2020-12-01T17:13:01.985Z,1606842781.985 [Startup] Stopped
2020-12-01T17:13:01.985Z,1606842781.985 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-12-01T17:13:01.985Z,1606842781.985 [Startup:A.GoToSurface] Stopped
2020-12-01T17:13:01.985Z,1606842781.985 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-01T17:13:02.386Z,1606842782.386 [MissionManager](IMPORTANT): Started mission Default
2020-12-01T17:13:02.386Z,1606842782.386 [Default] Running Loop=1
2020-12-01T17:13:02.386Z,1606842782.386 [Default](DEBUG): Aggregate::initialize Default
2020-12-01T17:13:02.386Z,1606842782.386 [Default:B.GoToSurface] Running Loop=1
2020-12-01T17:13:02.386Z,1606842782.386 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-01T17:13:02.387Z,1606842782.387 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-01T17:13:02.387Z,1606842782.387 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-01T17:13:02.387Z,1606842782.387 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-01T17:13:02.387Z,1606842782.387 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-01T17:13:02.404Z,1606842782.404 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-01T17:13:02.404Z,1606842782.404 [Default:A.Wait] Running Loop=1
2020-12-01T17:13:02.404Z,1606842782.404 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-12-01T17:13:15.717Z,1606842795.717 [Default:A.Wait](INFO): Done Waiting.
2020-12-01T17:13:15.717Z,1606842795.717 [Default:A.Wait] Stopped
2020-12-01T17:13:15.717Z,1606842795.717 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T17:13:16.113Z,1606842796.113 [Default:CheckIn] Running Loop=1
2020-12-01T17:13:16.114Z,1606842796.114 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T17:13:16.114Z,1606842796.114 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T17:13:16.524Z,1606842796.524 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-12-01T17:14:59.945Z,1606842899.945 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-01T17:15:18.970Z,1606842918.970 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-12-01T17:15:18.970Z,1606842918.970 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T17:15:18.980Z,1606842918.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T17:15:19.377Z,1606842919.377 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T17:15:19.378Z,1606842919.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-12-01T17:15:21.763Z,1606842921.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171520.00,A,3648.16634,N,12147.28157,W,0.039,0.00,011220,,,D*74
2020-12-01T17:15:21.766Z,1606842921.766 [NAL9602](INFO): GPS fix at 20201201T171520: (36.802772, -121.788026)
2020-12-01T17:15:21.788Z,1606842921.788 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T17:15:21.788Z,1606842921.788 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T17:15:22.204Z,1606842922.204 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-01T17:15:40.743Z,1606842940.743 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T164721/Courier0013.lzma
2020-12-01T17:15:41.746Z,1606842941.746 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Courier0013.lzma.bak
2020-12-01T17:15:41.746Z,1606842941.746 [DataOverHttps](INFO): SBD MOMSN=13372556
2020-12-01T17:15:54.489Z,1606842954.489 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T17:15:58.164Z,1606842958.164 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20201201T170908/Courier0000.lzma
2020-12-01T17:15:59.166Z,1606842959.166 [DataOverHttps](INFO): Moved sent file to Logs/20201201T170908/Courier0000.lzma.bak
2020-12-01T17:15:59.166Z,1606842959.166 [DataOverHttps](INFO): SBD MOMSN=13372558
2020-12-01T17:16:15.620Z,1606842975.620 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20201201T170908/Courier0004.lzma
2020-12-01T17:16:16.622Z,1606842976.622 [DataOverHttps](INFO): Moved sent file to Logs/20201201T170908/Courier0004.lzma.bak
2020-12-01T17:16:16.622Z,1606842976.622 [DataOverHttps](INFO): SBD MOMSN=13372565
2020-12-01T17:16:39.028Z,1606842999.028 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20201201T164721/Express0014.lzma
2020-12-01T17:16:40.030Z,1606843000.030 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Express0014.lzma.bak
2020-12-01T17:16:40.030Z,1606843000.030 [DataOverHttps](INFO): SBD MOMSN=13372568
2020-12-01T17:16:43.926Z,1606843003.926 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2020-12-01T17:16:43.941Z,1606843003.941 [BPC1](INFO): Received data from all battery sticks.
2020-12-01T17:16:56.505Z,1606843016.505 [DataOverHttps](INFO): Sending 1011 bytes from file Logs/20201201T170908/Express0001.lzma
2020-12-01T17:16:57.506Z,1606843017.506 [DataOverHttps](INFO): Moved sent file to Logs/20201201T170908/Express0001.lzma.bak
2020-12-01T17:16:57.506Z,1606843017.506 [DataOverHttps](INFO): SBD MOMSN=13372582
2020-12-01T17:17:14.812Z,1606843034.812 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20201201T170908/Express0005.lzma
2020-12-01T17:17:15.814Z,1606843035.814 [DataOverHttps](INFO): Moved sent file to Logs/20201201T170908/Express0005.lzma.bak
2020-12-01T17:17:15.814Z,1606843035.814 [DataOverHttps](INFO): SBD MOMSN=13372610
2020-12-01T17:17:17.047Z,1606843037.047 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T17:17:17.047Z,1606843037.047 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T17:17:17.047Z,1606843037.047 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T17:18:08.950Z,1606843088.950 [CommandLine](IMPORTANT): got command restart application
2020-12-01T17:18:09.956Z,1606843089.956 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:09.956Z,1606843089.956 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.048Z,1606843090.048 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2020-12-01T17:18:10.048Z,1606843090.048 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.049Z,1606843090.049 [CommandLine](INFO): Join timeout helper Thread ID is 6028
2020-12-01T17:18:10.049Z,1606843090.049 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-12-01T17:18:10.049Z,1606843090.049 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.050Z,1606843090.050 [NavChartDb](INFO): Join timeout helper Thread ID is 6029
2020-12-01T17:18:10.196Z,1606843090.196 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:10.196Z,1606843090.196 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.208Z,1606843090.208 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-12-01T17:18:10.208Z,1606843090.208 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.208Z,1606843090.208 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 6030
2020-12-01T17:18:10.500Z,1606843090.500 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:10.500Z,1606843090.500 [WetLabsUBAT](INFO): Powering down
2020-12-01T17:18:10.501Z,1606843090.501 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.516Z,1606843090.516 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-12-01T17:18:10.516Z,1606843090.516 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.516Z,1606843090.516 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6031
2020-12-01T17:18:10.728Z,1606843090.728 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:10.728Z,1606843090.728 [WetLabsBB2FL](INFO): Powering down
2020-12-01T17:18:10.729Z,1606843090.729 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.736Z,1606843090.736 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-12-01T17:18:10.736Z,1606843090.736 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:10.736Z,1606843090.736 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6032
2020-12-01T17:18:11.037Z,1606843091.037 [CTD_Seabird](INFO): Powering down
2020-12-01T17:18:11.052Z,1606843091.052 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:11.053Z,1606843091.053 [CTD_Seabird](INFO): Powering down
2020-12-01T17:18:11.068Z,1606843091.068 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:11.086Z,1606843091.086 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-12-01T17:18:11.087Z,1606843091.087 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:11.088Z,1606843091.088 [BackSeatDriver](INFO): Join timeout helper Thread ID is 6033
2020-12-01T17:18:11.340Z,1606843091.340 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:11.340Z,1606843091.340 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:11.360Z,1606843091.360 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-12-01T17:18:11.361Z,1606843091.361 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:11.361Z,1606843091.361 [Radio_Surface](INFO): Join timeout helper Thread ID is 6034
2020-12-01T17:18:11.696Z,1606843091.696 [Radio_Surface](INFO): Powering down
2020-12-01T17:18:11.697Z,1606843091.697 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:11.697Z,1606843091.697 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:11.709Z,1606843091.708 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-12-01T17:18:11.709Z,1606843091.709 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:11.709Z,1606843091.709 [Onboard](INFO): Join timeout helper Thread ID is 6035
2020-12-01T17:18:12.420Z,1606843092.420 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-12-01T17:18:13.108Z,1606843093.108 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:13.108Z,1606843093.108 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.121Z,1606843093.121 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-12-01T17:18:13.121Z,1606843093.121 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.121Z,1606843093.121 [DataOverHttps](INFO): Join timeout helper Thread ID is 6036
2020-12-01T17:18:13.424Z,1606843093.424 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:13.424Z,1606843093.424 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.444Z,1606843093.444 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-12-01T17:18:13.445Z,1606843093.445 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.445Z,1606843093.445 [logger](INFO): Join timeout helper Thread ID is 6037
2020-12-01T17:18:13.468Z,1606843093.468 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:13.468Z,1606843093.468 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.484Z,1606843093.484 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-12-01T17:18:13.485Z,1606843093.485 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.485Z,1606843093.485 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-12-01T17:18:13.485Z,1606843093.485 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.485Z,1606843093.485 [controlThread](INFO): Join timeout helper Thread ID is 6038
2020-12-01T17:18:13.573Z,1606843093.573 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T17:18:13.573Z,1606843093.573 [controlThread](DEBUG): Uninitializing ControlThread
2020-12-01T17:18:13.575Z,1606843093.575 [AHRS_M2](INFO): Powering down
2020-12-01T17:18:13.648Z,1606843093.648 [NAL9602](INFO): Powering down
2020-12-01T17:18:13.720Z,1606843093.720 [RDI_Pathfinder](INFO): Powering down
2020-12-01T17:18:13.721Z,1606843093.721 [DAT](INFO): Powering down
2020-12-01T17:18:13.841Z,1606843093.841 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-12-01T17:18:13.842Z,1606843093.842 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-12-01T17:18:13.842Z,1606843093.842 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-12-01T17:18:13.843Z,1606843093.843 [MissionManager](INFO): Uninitializing Mission Default
2020-12-01T17:18:13.843Z,1606843093.843 [Default] Stopped
2020-12-01T17:18:13.843Z,1606843093.843 [Default](DEBUG): Aggregate::uninitialize Default
2020-12-01T17:18:13.843Z,1606843093.843 [Default:B.GoToSurface] Stopped
2020-12-01T17:18:13.843Z,1606843093.843 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-01T17:18:13.843Z,1606843093.843 [Default:CheckIn] Stopped
2020-12-01T17:18:13.843Z,1606843093.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T17:18:13.843Z,1606843093.843 [Default:CheckIn:C.Wait] Stopped
2020-12-01T17:18:13.843Z,1606843093.843 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T17:18:13.845Z,1606843093.845 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-12-01T17:18:13.846Z,1606843093.846 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-12-01T17:18:13.846Z,1606843093.846 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-12-01T17:18:13.846Z,1606843093.846 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-12-01T17:18:13.847Z,1606843093.847 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-12-01T17:18:13.847Z,1606843093.847 [BuoyancyServo](INFO): Powering down
2020-12-01T17:18:13.860Z,1606843093.860 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-12-01T17:18:13.860Z,1606843093.860 [ElevatorServo](INFO): Powering down
2020-12-01T17:18:13.861Z,1606843093.861 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-12-01T17:18:13.861Z,1606843093.861 [MassServo](INFO): Powering down
2020-12-01T17:18:13.862Z,1606843093.862 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-01T17:18:13.862Z,1606843093.862 [RudderServo](INFO): Powering down
2020-12-01T17:18:13.862Z,1606843093.862 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-12-01T17:18:13.862Z,1606843093.862 [ThrusterServo](INFO): Powering down
2020-12-01T17:18:13.863Z,1606843093.863 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-12-01T17:18:13.863Z,1606843093.863 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-12-01T17:18:13.864Z,1606843093.864 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-12-01T17:18:13.864Z,1606843093.864 [CBIT](DEBUG): Powering off loads.
2020-12-01T17:18:13.876Z,1606843093.876 [CBIT](DEBUG): Disabling WDT.
2020-12-01T17:18:13.888Z,1606843093.888 [CBIT](DEBUG): Opening all GF detection circuits.
2020-12-01T17:18:13.889Z,1606843093.889 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.985Z,1606843093.985 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.988Z,1606843093.988 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:13.993Z,1606843093.993 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:14.050Z,1606843094.050 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:14.051Z,1606843094.051 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2020-12-01T17:18:14.052Z,1606843094.052 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-12-01T17:18:14.054Z,1606843094.054 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:14.058Z,1606843094.058 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:14.061Z,1606843094.061 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:14.070Z,1606843094.070 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-01T17:18:14.142Z,1606843094.142 [logger ThreadHandler](INFO): Thread cancelled.