2020-11-05T21:16:34.786Z,1604610994.786 [Supervisor](DEBUG): Initializing supervisor.
2020-11-05T21:16:34.789Z,1604610994.789 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-11-05T21:16:34.790Z,1604610994.790 [SyncHandler](INFO): Protected caller Thread ID is 1764
2020-11-05T21:16:34.790Z,1604610994.790 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-11-05T21:16:34.792Z,1604610994.792 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-11-05T21:16:34.792Z,1604610994.792 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1765
2020-11-05T21:16:34.796Z,1604610994.796 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-11-05T21:16:34.813Z,1604610994.813 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-11-05T21:16:34.814Z,1604610994.814 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-11-05T21:16:34.814Z,1604610994.814 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1766
2020-11-05T21:16:34.815Z,1604610994.815 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-11-05T21:16:34.816Z,1604610994.816 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-11-05T21:16:34.816Z,1604610994.816 [logger ThreadHandler](INFO): Protected caller Thread ID is 1767
2020-11-05T21:16:34.819Z,1604610994.819 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-11-05T21:16:34.819Z,1604610994.819 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-11-05T21:16:34.820Z,1604610994.820 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-11-05T21:16:34.916Z,1604610994.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-11-05T21:16:34.916Z,1604610994.916 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-11-05T21:16:35.517Z,1604610995.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-11-05T21:16:35.517Z,1604610995.517 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-11-05T21:16:35.721Z,1604610995.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-11-05T21:16:35.722Z,1604610995.722 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-11-05T21:16:35.821Z,1604610995.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-11-05T21:16:35.822Z,1604610995.822 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-11-05T21:16:35.927Z,1604610995.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-11-05T21:16:35.928Z,1604610995.928 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-11-05T21:16:36.010Z,1604610996.010 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-11-05T21:16:36.151Z,1604610996.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-11-05T21:16:36.152Z,1604610996.152 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-11-05T21:16:36.448Z,1604610996.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-11-05T21:16:36.448Z,1604610996.448 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-11-05T21:16:36.955Z,1604610996.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-11-05T21:16:36.955Z,1604610996.955 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-11-05T21:16:37.100Z,1604610997.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-11-05T21:16:37.101Z,1604610997.101 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-11-05T21:16:37.296Z,1604610997.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-11-05T21:16:37.296Z,1604610997.296 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-11-05T21:16:37.790Z,1604610997.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-11-05T21:16:37.790Z,1604610997.790 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-11-05T21:16:38.010Z,1604610998.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-11-05T21:16:38.011Z,1604610998.011 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-11-05T21:16:38.214Z,1604610998.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-11-05T21:16:38.214Z,1604610998.214 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-11-05T21:16:38.628Z,1604610998.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-11-05T21:16:38.629Z,1604610998.629 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-11-05T21:16:38.994Z,1604610998.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-11-05T21:16:38.996Z,1604610998.996 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-11-05T21:16:38.997Z,1604610998.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-11-05T21:16:39.306Z,1604610999.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-11-05T21:16:39.508Z,1604610999.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-11-05T21:16:39.620Z,1604610999.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-11-05T21:16:39.762Z,1604610999.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-11-05T21:16:39.944Z,1604610999.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-11-05T21:16:40.321Z,1604611000.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-11-05T21:16:40.573Z,1604611000.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-11-05T21:16:40.573Z,1604611000.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-11-05T21:16:40.668Z,1604611000.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-11-05T21:16:40.767Z,1604611000.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-11-05T21:16:40.900Z,1604611000.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-11-05T21:16:41.001Z,1604611001.001 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-11-05T21:16:41.002Z,1604611001.002 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-11-05T21:16:41.016Z,1604611001.016 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-11-05T21:16:41.092Z,1604611001.092 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-11-05T21:16:41.093Z,1604611001.093 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-11-05T21:16:41.138Z,1604611001.138 [VerticalControl](DEBUG): Construct VerticalControl.
2020-11-05T21:16:41.198Z,1604611001.198 [VerticalControl] Loaded
2020-11-05T21:16:41.198Z,1604611001.198 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-11-05T21:16:41.199Z,1604611001.199 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-11-05T21:16:41.236Z,1604611001.236 [HorizontalControl] Loaded
2020-11-05T21:16:41.236Z,1604611001.236 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-11-05T21:16:41.237Z,1604611001.237 [SpeedControl](DEBUG): Construct SpeedControl.
2020-11-05T21:16:41.240Z,1604611001.240 [SpeedControl] Loaded
2020-11-05T21:16:41.240Z,1604611001.240 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-11-05T21:16:41.241Z,1604611001.241 [LoopControl](DEBUG): Construct LoopControl.
2020-11-05T21:16:41.241Z,1604611001.241 [LoopControl] Loaded
2020-11-05T21:16:41.242Z,1604611001.242 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-11-05T21:16:41.242Z,1604611001.242 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-11-05T21:16:41.242Z,1604611001.242 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-11-05T21:16:41.280Z,1604611001.280 [DepthRateCalculator] Loaded
2020-11-05T21:16:41.280Z,1604611001.280 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-11-05T21:16:41.283Z,1604611001.283 [PitchRateCalculator] Loaded
2020-11-05T21:16:41.284Z,1604611001.284 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-11-05T21:16:41.292Z,1604611001.292 [SpeedCalculator] Loaded
2020-11-05T21:16:41.292Z,1604611001.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-11-05T21:16:41.303Z,1604611001.303 [TempGradientCalculator] Loaded
2020-11-05T21:16:41.303Z,1604611001.303 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-11-05T21:16:41.306Z,1604611001.306 [YawRateCalculator] Loaded
2020-11-05T21:16:41.306Z,1604611001.306 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-11-05T21:16:41.321Z,1604611001.321 [ElevatorOffsetCalculator] Loaded
2020-11-05T21:16:41.321Z,1604611001.321 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-11-05T21:16:41.321Z,1604611001.321 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-11-05T21:16:41.322Z,1604611001.322 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-11-05T21:16:41.347Z,1604611001.347 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-11-05T21:16:41.347Z,1604611001.347 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-11-05T21:16:41.431Z,1604611001.431 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-11-05T21:16:41.431Z,1604611001.431 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-11-05T21:16:41.707Z,1604611001.707 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-11-05T21:16:41.708Z,1604611001.708 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-11-05T21:16:41.804Z,1604611001.804 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-11-05T21:16:41.805Z,1604611001.805 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-11-05T21:16:42.121Z,1604611002.121 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-11-05T21:16:42.124Z,1604611002.124 [AHRS_M2](INFO): created writer for : platform_orientation
2020-11-05T21:16:42.125Z,1604611002.125 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-11-05T21:16:42.128Z,1604611002.128 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-11-05T21:16:42.129Z,1604611002.129 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-11-05T21:16:42.131Z,1604611002.131 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-11-05T21:16:42.132Z,1604611002.132 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-11-05T21:16:42.135Z,1604611002.135 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-11-05T21:16:42.172Z,1604611002.172 [AHRS_M2] Loaded
2020-11-05T21:16:42.172Z,1604611002.172 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-11-05T21:16:42.245Z,1604611002.245 [DataOverHttps] Loaded
2020-11-05T21:16:42.245Z,1604611002.245 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-11-05T21:16:42.246Z,1604611002.246 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-11-05T21:16:42.247Z,1604611002.247 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1849
2020-11-05T21:16:42.264Z,1604611002.264 [Depth_Keller] Loaded
2020-11-05T21:16:42.264Z,1604611002.264 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-11-05T21:16:42.266Z,1604611002.266 [DropWeight] Loaded
2020-11-05T21:16:42.267Z,1604611002.267 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-11-05T21:16:42.320Z,1604611002.320 [NAL9602] Loaded
2020-11-05T21:16:42.320Z,1604611002.320 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-11-05T21:16:42.346Z,1604611002.346 [Onboard] Loaded
2020-11-05T21:16:42.346Z,1604611002.346 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-11-05T21:16:42.347Z,1604611002.347 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-11-05T21:16:42.347Z,1604611002.347 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1850
2020-11-05T21:16:42.357Z,1604611002.357 [Radio_Surface] Loaded
2020-11-05T21:16:42.358Z,1604611002.358 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-11-05T21:16:42.358Z,1604611002.358 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-11-05T21:16:42.359Z,1604611002.359 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1851
2020-11-05T21:16:42.430Z,1604611002.430 [DAT] Loaded
2020-11-05T21:16:42.430Z,1604611002.430 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-11-05T21:16:43.199Z,1604611003.199 [BPC1] Loaded
2020-11-05T21:16:43.199Z,1604611003.199 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-11-05T21:16:43.200Z,1604611003.200 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-11-05T21:16:43.200Z,1604611003.200 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-11-05T21:16:43.276Z,1604611003.276 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-11-05T21:16:43.276Z,1604611003.276 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-11-05T21:16:43.288Z,1604611003.288 [NavChart] Loaded
2020-11-05T21:16:43.288Z,1604611003.288 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-11-05T21:16:43.292Z,1604611003.292 [UniversalFixResidualReporter] Loaded
2020-11-05T21:16:43.292Z,1604611003.292 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-11-05T21:16:43.293Z,1604611003.293 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-11-05T21:16:43.293Z,1604611003.293 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-11-05T21:16:43.420Z,1604611003.420 [SBIT](DEBUG): Construct Startup Built In Test.
2020-11-05T21:16:43.429Z,1604611003.429 [SBIT] Loaded
2020-11-05T21:16:43.429Z,1604611003.429 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-11-05T21:16:43.430Z,1604611003.430 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-11-05T21:16:43.442Z,1604611003.442 [IBIT] Loaded
2020-11-05T21:16:43.442Z,1604611003.442 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-11-05T21:16:43.445Z,1604611003.445 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-11-05T21:16:43.541Z,1604611003.541 [CBIT] Loaded
2020-11-05T21:16:43.541Z,1604611003.541 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-11-05T21:16:43.542Z,1604611003.542 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-11-05T21:16:43.542Z,1604611003.542 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-11-05T21:16:43.650Z,1604611003.650 [BuoyancyServo] Loaded
2020-11-05T21:16:43.651Z,1604611003.651 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-11-05T21:16:43.667Z,1604611003.667 [ElevatorServo] Loaded
2020-11-05T21:16:43.668Z,1604611003.668 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-11-05T21:16:43.684Z,1604611003.684 [MassServo] Loaded
2020-11-05T21:16:43.684Z,1604611003.684 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-11-05T21:16:43.699Z,1604611003.699 [RudderServo] Loaded
2020-11-05T21:16:43.699Z,1604611003.699 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-11-05T21:16:43.714Z,1604611003.714 [ThrusterServo] Loaded
2020-11-05T21:16:43.715Z,1604611003.715 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-11-05T21:16:43.715Z,1604611003.715 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-11-05T21:16:43.716Z,1604611003.716 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-11-05T21:16:43.733Z,1604611003.733 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-11-05T21:16:43.734Z,1604611003.734 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-11-05T21:16:43.942Z,1604611003.942 [BackSeatDriver] Loaded
2020-11-05T21:16:43.943Z,1604611003.943 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-11-05T21:16:43.944Z,1604611003.944 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B064E0
2020-11-05T21:16:43.944Z,1604611003.944 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 1852
2020-11-05T21:16:43.963Z,1604611003.963 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-11-05T21:16:43.966Z,1604611003.966 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-11-05T21:16:43.966Z,1604611003.966 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-11-05T21:16:43.969Z,1604611003.969 [CTD_Seabird](INFO): created writer for : depth
2020-11-05T21:16:43.969Z,1604611003.969 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-11-05T21:16:43.972Z,1604611003.972 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-11-05T21:16:43.972Z,1604611003.972 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-11-05T21:16:43.975Z,1604611003.975 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-11-05T21:16:43.976Z,1604611003.976 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-11-05T21:16:43.978Z,1604611003.978 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-11-05T21:16:43.979Z,1604611003.979 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-11-05T21:16:43.982Z,1604611003.982 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-11-05T21:16:43.983Z,1604611003.983 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-11-05T21:16:43.987Z,1604611003.987 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-11-05T21:16:43.999Z,1604611003.999 [CTD_Seabird] Loaded
2020-11-05T21:16:43.000Z,1604611004.000 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-11-05T21:16:44.001Z,1604611004.001 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B364E0
2020-11-05T21:16:44.001Z,1604611004.001 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1853
2020-11-05T21:16:44.018Z,1604611004.018 [PAR_Licor] Loaded
2020-11-05T21:16:44.018Z,1604611004.018 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-11-05T21:16:44.030Z,1604611004.030 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-11-05T21:16:44.031Z,1604611004.031 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-11-05T21:16:44.033Z,1604611004.033 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-11-05T21:16:44.033Z,1604611004.033 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-11-05T21:16:44.035Z,1604611004.035 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-11-05T21:16:44.035Z,1604611004.035 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-11-05T21:16:44.037Z,1604611004.037 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-11-05T21:16:44.037Z,1604611004.037 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-11-05T21:16:44.039Z,1604611004.039 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-11-05T21:16:44.039Z,1604611004.039 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-11-05T21:16:44.041Z,1604611004.041 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-11-05T21:16:44.041Z,1604611004.041 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-11-05T21:16:44.043Z,1604611004.043 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-11-05T21:16:44.043Z,1604611004.043 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-11-05T21:16:44.045Z,1604611004.045 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.047Z,1604611004.047 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.048Z,1604611004.048 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.048Z,1604611004.048 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.050Z,1604611004.050 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.050Z,1604611004.050 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.052Z,1604611004.052 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.052Z,1604611004.052 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-05T21:16:44.054Z,1604611004.054 [WetLabsBB2FL] Loaded
2020-11-05T21:16:44.054Z,1604611004.054 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-11-05T21:16:44.055Z,1604611004.055 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0
2020-11-05T21:16:44.056Z,1604611004.056 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1854
2020-11-05T21:16:44.076Z,1604611004.076 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-11-05T21:16:44.077Z,1604611004.077 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-11-05T21:16:44.077Z,1604611004.077 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-11-05T21:16:44.080Z,1604611004.080 [WetLabsUBAT](CRITICAL): No configuration setting for: WetLabsUBAT.optionalArgs
2020-11-05T21:16:44.082Z,1604611004.082 [WetLabsUBAT](CRITICAL): No configuration setting for: WetLabsUBAT.emulateHardware
2020-11-05T21:16:44.084Z,1604611004.084 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: UBAT0051
2020-11-05T21:16:44.084Z,1604611004.084 [WetLabsUBAT](CRITICAL): Error loading configuration parameters during WetLabsUBAT::start()
2020-11-05T21:16:44.084Z,1604611004.084 [WetLabsUBAT] Data Fault, FailCount= 1
2020-11-05T21:16:44.084Z,1604611004.084 [WetLabsUBAT](ERROR): Data Fault
2020-11-05T21:16:44.084Z,1604611004.084 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-11-05T21:16:44.084Z,1604611004.084 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-11-05T21:16:44.086Z,1604611004.086 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-11-05T21:16:44.086Z,1604611004.086 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-11-05T21:16:44.089Z,1604611004.089 [WetLabsUBAT] Loaded
2020-11-05T21:16:44.089Z,1604611004.089 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-11-05T21:16:44.090Z,1604611004.090 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B964E0
2020-11-05T21:16:44.091Z,1604611004.091 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1855
2020-11-05T21:16:44.091Z,1604611004.091 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-11-05T21:16:44.109Z,1604611004.109 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-11-05T21:16:44.110Z,1604611004.110 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-11-05T21:16:44.153Z,1604611004.153 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-11-05T21:16:44.154Z,1604611004.154 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC64E0
2020-11-05T21:16:44.155Z,1604611004.155 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1856
2020-11-05T21:16:44.166Z,1604611004.166 [Supervisor](INFO): Main Thread ID is 828
2020-11-05T21:16:44.166Z,1604611004.166 [Supervisor](DEBUG): Running supervisor.
2020-11-05T21:16:44.166Z,1604611004.166 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1857
2020-11-05T21:16:44.170Z,1604611004.170 [controlThread ThreadHandler](INFO): Handler Thread ID is 1858
2020-11-05T21:16:44.171Z,1604611004.171 [controlThread](DEBUG): Initializing ControlThread
2020-11-05T21:16:44.171Z,1604611004.171 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-11-05T21:16:44.173Z,1604611004.173 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-11-05T21:16:44.174Z,1604611004.174 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-11-05T21:16:44.174Z,1604611004.174 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-11-05T21:16:44.174Z,1604611004.174 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-11-05T21:16:44.175Z,1604611004.175 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-11-05T21:16:44.175Z,1604611004.175 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-11-05T21:16:44.175Z,1604611004.175 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-11-05T21:16:44.176Z,1604611004.176 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-11-05T21:16:44.176Z,1604611004.176 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-11-05T21:16:44.180Z,1604611004.180 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-11-05T21:16:44.180Z,1604611004.180 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-11-05T21:16:44.180Z,1604611004.180 [SBIT](INFO): Initialize SBIT Component.
2020-11-05T21:16:44.181Z,1604611004.181 [SBIT](IMPORTANT): git: 2020-11-03
2020-11-05T21:16:44.181Z,1604611004.181 [SBIT](INFO): git hash: 8c5f527fc94ff6615863ceb7263f01b68d6bddc2
2020-11-05T21:16:44.181Z,1604611004.181 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-11-05T21:16:44.182Z,1604611004.182 [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-11-05T21:16:44.184Z,1604611004.184 [SBIT](INFO): Beginning SBIT in 26.000000 seconds.
2020-11-05T21:16:44.184Z,1604611004.184 [IBIT](INFO): Initialize IBIT Component.
2020-11-05T21:16:44.185Z,1604611004.185 [CBIT](DEBUG): Initialize CBIT Component.
2020-11-05T21:16:44.186Z,1604611004.186 [logger ThreadHandler](INFO): Handler Thread ID is 1859
2020-11-05T21:16:44.203Z,1604611004.203 [CBIT](DEBUG): Initialized mux pins.
2020-11-05T21:16:44.203Z,1604611004.203 [CBIT](DEBUG): Initializing the watchdog timer.
2020-11-05T21:16:44.220Z,1604611004.220 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1860
2020-11-05T21:16:44.221Z,1604611004.221 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-11-05T21:16:44.227Z,1604611004.227 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-11-05T21:16:44.227Z,1604611004.227 [CBIT](DEBUG): Initializing heartbeat.
2020-11-05T21:16:44.228Z,1604611004.228 [Onboard ThreadHandler](INFO): Handler Thread ID is 1861
2020-11-05T21:16:44.234Z,1604611004.234 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1862
2020-11-05T21:16:44.280Z,1604611004.280 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 1863
2020-11-05T21:16:44.291Z,1604611004.291 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1864
2020-11-05T21:16:44.292Z,1604611004.292 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-11-05T21:16:44.297Z,1604611004.297 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1866
2020-11-05T21:16:44.298Z,1604611004.298 [WetLabsBB2FL](INFO): Powering down
2020-11-05T21:16:44.299Z,1604611004.299 [CBIT](DEBUG): Deactivating GF circuits.
2020-11-05T21:16:44.299Z,1604611004.299 [CBIT](DEBUG): Deactivating emergency mode.
2020-11-05T21:16:44.323Z,1604611004.323 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1867
2020-11-05T21:16:44.325Z,1604611004.325 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1868
2020-11-05T21:16:44.328Z,1604611004.328 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-11-05T21:16:44.328Z,1604611004.328 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-11-05T21:16:44.329Z,1604611004.329 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-11-05T21:16:44.329Z,1604611004.329 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-11-05T21:16:44.329Z,1604611004.329 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-11-05T21:16:44.329Z,1604611004.329 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-11-05T21:16:44.329Z,1604611004.329 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-11-05T21:16:44.329Z,1604611004.329 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-11-05T21:16:44.330Z,1604611004.330 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-11-05T21:16:44.330Z,1604611004.330 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-11-05T21:16:44.330Z,1604611004.330 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-11-05T21:16:44.330Z,1604611004.330 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-11-05T21:16:44.330Z,1604611004.330 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-11-05T21:16:44.330Z,1604611004.330 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-11-05T21:16:44.330Z,1604611004.330 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-11-05T21:16:44.331Z,1604611004.331 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-11-05T21:16:44.336Z,1604611004.336 [CBIT](DEBUG): Backplane powered.
2020-11-05T21:16:44.340Z,1604611004.340 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-11-05T21:16:44.374Z,1604611004.374 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-05T21:16:44.446Z,1604611004.446 [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-11-05T21:16:44.446Z,1604611004.446 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-11-05T21:16:44.521Z,1604611004.521 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-11-05T21:16:44.522Z,1604611004.522 [Default:A.Wait](DEBUG): Construct Wait.
2020-11-05T21:16:44.524Z,1604611004.524 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-05T21:16:44.542Z,1604611004.542 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-11-05T21:16:44.569Z,1604611004.569 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-11-05T21:16:44.574Z,1604611004.574 [Default:E.Execute](DEBUG): Construct Execute.
2020-11-05T21:16:44.585Z,1604611004.585 [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-11-05T21:16:44.590Z,1604611004.590 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,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-11-05T21:16:44.603Z,1604611004.603 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-05T21:16:44.644Z,1604611004.644 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-11-05T21:16:44.652Z,1604611004.652 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-11-05T21:16:44.653Z,1604611004.653 [DAT](INFO): Powering up
2020-11-05T21:16:44.653Z,1604611004.653 [DAT](DEBUG): Initializing DAT.
2020-11-05T21:16:44.668Z,1604611004.668 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-11-05T21:16:44.691Z,1604611004.691 [Radio_Surface](INFO): Powering up
2020-11-05T21:16:44.764Z,1604611004.764 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-11-05T21:16:44.794Z,1604611004.794 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-11-05T21:16:44.815Z,1604611004.815 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-11-05T21:16:44.817Z,1604611004.817 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-11-05T21:16:44.831Z,1604611004.831 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-11-05T21:16:44.832Z,1604611004.832 [MassServo](DEBUG): Initializing EZServoServo.
2020-11-05T21:16:44.839Z,1604611004.839 [MassServo](DEBUG): Initializing MassServo.
2020-11-05T21:16:44.840Z,1604611004.840 [RudderServo](DEBUG): Initializing EZServoServo.
2020-11-05T21:16:44.855Z,1604611004.855 [RudderServo](DEBUG): Initializing RudderServo.
2020-11-05T21:16:44.856Z,1604611004.856 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-11-05T21:16:44.863Z,1604611004.863 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-11-05T21:16:44.866Z,1604611004.866 [CommandLine](FAULT): Scheduling is paused
2020-11-05T21:16:44.866Z,1604611004.866 [CBIT](INFO): Critical error at 20201105T211644
2020-11-05T21:16:44.866Z,1604611004.866 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-11-05T21:16:44.870Z,1604611004.870 [CBIT](ERROR): Data Fault in component: WetLabsUBAT
2020-11-05T21:16:44.870Z,1604611004.870 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-11-05T21:16:44.870Z,1604611004.870 [WetLabsUBAT] No Fault, FailCount= 1
2020-11-05T21:16:45.020Z,1604611005.020 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-11-05T21:16:45.020Z,1604611005.020 [DropWeight] Hardware Fault, FailCount= 1
2020-11-05T21:16:45.020Z,1604611005.020 [DropWeight](ERROR): Hardware Fault
2020-11-05T21:16:45.032Z,1604611005.032 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-11-05T21:16:45.073Z,1604611005.073 [CBIT](INFO): Critical error at 20201105T211645
2020-11-05T21:16:45.076Z,1604611005.076 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-11-05T21:16:45.076Z,1604611005.076 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-11-05T21:16:45.131Z,1604611005.131 [WetLabsUBAT](INFO): Initializing
2020-11-05T21:16:45.133Z,1604611005.133 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-11-05T21:16:45.133Z,1604611005.133 [WetLabsUBAT](INFO): Powering up
2020-11-05T21:16:45.134Z,1604611005.134 [WetLabsUBAT](INFO): Checking LCM
2020-11-05T21:16:45.134Z,1604611005.134 [WetLabsUBAT](INFO): LCM OK
2020-11-05T21:16:45.138Z,1604611005.138 [WetLabsUBAT](ERROR): Error configuring driver
2020-11-05T21:16:45.139Z,1604611005.139 [WetLabsUBAT] Software Fault, FailCount= 2
2020-11-05T21:16:45.139Z,1604611005.139 [WetLabsUBAT](ERROR): Software Fault
2020-11-05T21:16:45.539Z,1604611005.539 [WetLabsUBAT](INFO): Powering down
2020-11-05T21:16:45.743Z,1604611005.743 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-11-05T21:16:45.743Z,1604611005.743 [RudderServo](FAULT): Rudder failed to initialize
2020-11-05T21:16:45.743Z,1604611005.743 [RudderServo] Communications Fault, FailCount= 1
2020-11-05T21:16:45.743Z,1604611005.743 [RudderServo](ERROR): Communications Fault
2020-11-05T21:16:45.852Z,1604611005.852 [CBIT](INFO): Critical error at 20201105T211645
2020-11-05T21:16:45.855Z,1604611005.855 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-11-05T21:16:45.855Z,1604611005.855 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-11-05T21:16:46.024Z,1604611006.024 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-05T21:16:46.024Z,1604611006.024 [RudderServo](INFO): Powering down
2020-11-05T21:16:46.686Z,1604611006.686 [RudderServo](DEBUG): Initializing EZServoServo.
2020-11-05T21:16:46.804Z,1604611006.804 [RudderServo](DEBUG): Initializing RudderServo.
2020-11-05T21:16:46.808Z,1604611006.808 [CBIT](INFO): Clearing failed state for component RudderServo
2020-11-05T21:16:46.808Z,1604611006.808 [RudderServo] No Fault, FailCount= 1
2020-11-05T21:16:46.808Z,1604611006.808 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-11-05T21:16:46.808Z,1604611006.808 [WetLabsUBAT] No Fault, FailCount= 2
2020-11-05T21:16:47.155Z,1604611007.155 [WetLabsUBAT](INFO): Initializing
2020-11-05T21:16:47.157Z,1604611007.157 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-11-05T21:16:47.157Z,1604611007.157 [WetLabsUBAT](INFO): Powering up
2020-11-05T21:16:47.157Z,1604611007.157 [WetLabsUBAT](INFO): Checking LCM
2020-11-05T21:16:47.158Z,1604611007.158 [WetLabsUBAT](INFO): LCM OK
2020-11-05T21:16:47.160Z,1604611007.160 [WetLabsUBAT](ERROR): Error configuring driver
2020-11-05T21:16:47.160Z,1604611007.160 [WetLabsUBAT] Software Fault, FailCount= 3
2020-11-05T21:16:47.160Z,1604611007.160 [WetLabsUBAT](ERROR): Software Fault
2020-11-05T21:16:47.571Z,1604611007.571 [WetLabsUBAT](INFO): Powering down
2020-11-05T21:16:47.662Z,1604611007.662 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-11-05T21:16:48.459Z,1604611008.459 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-11-05T21:16:48.459Z,1604611008.459 [WetLabsUBAT] No Fault, FailCount= 3
2020-11-05T21:16:48.783Z,1604611008.783 [WetLabsUBAT](INFO): Initializing
2020-11-05T21:16:48.785Z,1604611008.785 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-11-05T21:16:48.785Z,1604611008.785 [WetLabsUBAT](INFO): Powering up
2020-11-05T21:16:48.786Z,1604611008.786 [WetLabsUBAT](INFO): Checking LCM
2020-11-05T21:16:48.786Z,1604611008.786 [WetLabsUBAT](INFO): LCM OK
2020-11-05T21:16:48.790Z,1604611008.790 [WetLabsUBAT](ERROR): Error configuring driver
2020-11-05T21:16:48.790Z,1604611008.790 [WetLabsUBAT] Software Fault, FailCount= 4
2020-11-05T21:16:48.790Z,1604611008.790 [WetLabsUBAT](ERROR): Software Fault
2020-11-05T21:16:48.903Z,1604611008.903 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-11-05T21:16:49.191Z,1604611009.191 [WetLabsUBAT](INFO): Powering down
2020-11-05T21:16:50.127Z,1604611010.127 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-11-05T21:16:50.127Z,1604611010.127 [WetLabsUBAT] No Fault, FailCount= 4
2020-11-05T21:16:50.403Z,1604611010.403 [WetLabsUBAT](INFO): Initializing
2020-11-05T21:16:50.405Z,1604611010.405 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-11-05T21:16:50.405Z,1604611010.405 [WetLabsUBAT](INFO): Powering up
2020-11-05T21:16:50.405Z,1604611010.405 [WetLabsUBAT](INFO): Checking LCM
2020-11-05T21:16:50.406Z,1604611010.406 [WetLabsUBAT](INFO): LCM OK
2020-11-05T21:16:50.408Z,1604611010.408 [WetLabsUBAT](ERROR): Error configuring driver
2020-11-05T21:16:50.408Z,1604611010.408 [WetLabsUBAT] Software Fault, FailCount= 5
2020-11-05T21:16:50.408Z,1604611010.408 [WetLabsUBAT](ERROR): Software Fault
2020-11-05T21:16:50.603Z,1604611010.603 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-11-05T21:16:50.603Z,1604611010.603 [CBIT](CRITICAL): Software Fault in component: WetLabsUBAT
2020-11-05T21:16:50.812Z,1604611010.812 [WetLabsUBAT](INFO): Powering down
2020-11-05T21:16:50.957Z,1604611010.957 [CBIT](INFO): Critical error at 20201105T211650
2020-11-05T21:16:51.328Z,1604611011.328 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-11-05T21:16:54.392Z,1604611014.392 [CBIT](CRITICAL): Environmental Failure. Press:14.501672 PSI. Humidity:52%. Temp:24 C. ABORTING MISSION
2020-11-05T21:16:54.778Z,1604611014.778 [CBIT](INFO): Critical error at 20201105T211654
2020-11-05T21:16:58.798Z,1604611018.798 [DAT](INFO): commRate: 800
2020-11-05T21:17:00.817Z,1604611020.817 [DAT](INFO): entering command mode
2020-11-05T21:17:01.221Z,1604611021.221 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:01.641Z,1604611021.641 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:02.029Z,1604611022.029 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:02.433Z,1604611022.433 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:02.836Z,1604611022.836 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:03.237Z,1604611023.237 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:03.649Z,1604611023.649 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:04.045Z,1604611024.045 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:04.449Z,1604611024.449 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:04.857Z,1604611024.857 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:05.257Z,1604611025.257 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:05.661Z,1604611025.661 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:06.065Z,1604611026.065 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:06.469Z,1604611026.469 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:06.873Z,1604611026.873 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:07.277Z,1604611027.277 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:07.680Z,1604611027.680 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:08.085Z,1604611028.085 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:08.493Z,1604611028.493 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:08.893Z,1604611028.893 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:09.297Z,1604611029.297 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:09.705Z,1604611029.705 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:10.109Z,1604611030.109 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:10.509Z,1604611030.509 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:10.921Z,1604611030.921 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:10.960Z,1604611030.960 [SBIT](IMPORTANT): Beginning Startup BIT
2020-11-05T21:17:10.968Z,1604611030.968 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-05T21:17:11.317Z,1604611031.317 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:11.724Z,1604611031.724 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:12.133Z,1604611032.133 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:12.533Z,1604611032.533 [NAL9602](INFO): Powering up NAL9602
2020-11-05T21:17:12.534Z,1604611032.534 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:12.933Z,1604611032.933 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:13.341Z,1604611033.341 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:13.757Z,1604611033.757 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:14.153Z,1604611034.153 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:14.553Z,1604611034.553 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:14.957Z,1604611034.957 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:15.356Z,1604611035.356 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:15.761Z,1604611035.761 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:16.177Z,1604611036.177 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-05T21:17:16.177Z,1604611036.177 [DAT](FAULT): failed to enter command mode
2020-11-05T21:17:16.573Z,1604611036.573 [DAT](INFO): entering command mode
2020-11-05T21:17:16.656Z,1604611036.656 [CommandLine](IMPORTANT): got command show variable optionalar
2020-11-05T21:17:16.849Z,1604611036.849 [CommandLine](IMPORTANT): WetlabsUBAT.optionalArgs (none)
2020-11-05T21:17:16.982Z,1604611036.982 [DAT](INFO): setting verbose to 3
2020-11-05T21:17:17.005Z,1604611037.005 [CommandLine](IMPORTANT): WetLabsUBAT.optionalArgs (none)
2020-11-05T21:17:17.386Z,1604611037.386 [DAT](INFO): set verbose to 3
2020-11-05T21:17:17.386Z,1604611037.386 [DAT](INFO): setting DatVerbose to 27440
2020-11-05T21:17:17.782Z,1604611037.782 [DAT](INFO): set DatVerbose to 27440
2020-11-05T21:17:17.782Z,1604611037.782 [DAT](INFO): setting transmit power to 8
2020-11-05T21:17:18.186Z,1604611038.186 [DAT](INFO): set transmit power to 8
2020-11-05T21:17:18.186Z,1604611038.186 [DAT](INFO): setting local address to 9
2020-11-05T21:17:18.594Z,1604611038.594 [DAT](INFO): set local address to 9
2020-11-05T21:17:21.912Z,1604611041.912 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.004841
CHAN A1 (24V): -0.001031
CHAN A2 (12V): -0.006930
CHAN A3 (5V): -0.002581
CHAN B0 (3.3V): -0.000637
CHAN B1 (3.15aV): -0.000428
CHAN B2 (3.15bV): -0.000119
CHAN B3 (GND): 0.001921
OPEN: 0.005423
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-05T21:17:23.176Z,1604611043.176 [CommandLine](IMPORTANT): got command get WetlabsUBAT.optionalArgs
2020-11-05T21:17:23.176Z,1604611043.176 [CommandLine](IMPORTANT): WetlabsUBAT.optionalArgs 
2020-11-05T21:17:23.441Z,1604611043.441 [NAL9602](INFO): NAL9602 initialized
2020-11-05T21:17:34.929Z,1604611054.929 [CommandLine](IMPORTANT): got command failComponent
2020-11-05T21:17:34.929Z,1604611054.929 [CommandLine](IMPORTANT): Failed components:
2020-11-05T21:17:34.929Z,1604611054.929 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-11-05T21:17:34.930Z,1604611054.930 [CommandLine](IMPORTANT): WetLabsUBAT: Software Fault
2020-11-05T21:17:41.593Z,1604611061.593 [CommandLine](IMPORTANT): got command failComponent none WetLabsUBAT
2020-11-05T21:17:41.593Z,1604611061.593 [WetLabsUBAT] No Fault, FailCount= 5
2020-11-05T21:17:41.593Z,1604611061.593 [CommandLine](IMPORTANT): WetLabsUBAT failureMode is No Fault
2020-11-05T21:17:41.719Z,1604611061.719 [WetLabsUBAT](INFO): Initializing
2020-11-05T21:17:41.721Z,1604611061.721 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-11-05T21:17:41.721Z,1604611061.721 [WetLabsUBAT](INFO): Powering up
2020-11-05T21:17:41.722Z,1604611061.722 [WetLabsUBAT](INFO): Checking LCM
2020-11-05T21:17:41.722Z,1604611061.722 [WetLabsUBAT](INFO): LCM OK
2020-11-05T21:17:41.722Z,1604611061.722 [WetLabsUBAT](ERROR): Error configuring driver
2020-11-05T21:17:41.722Z,1604611061.722 [WetLabsUBAT] Software Fault, FailCount= 6
2020-11-05T21:17:41.722Z,1604611061.722 [WetLabsUBAT](ERROR): Software Fault
2020-11-05T21:17:42.127Z,1604611062.127 [WetLabsUBAT](INFO): Powering down
2020-11-05T21:17:42.188Z,1604611062.188 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-11-05T21:17:42.188Z,1604611062.188 [CBIT](CRITICAL): Software Fault in component: WetLabsUBAT
2020-11-05T21:17:42.485Z,1604611062.485 [CBIT](INFO): Critical error at 20201105T211742
2020-11-05T21:18:04.672Z,1604611084.672 [SBIT](IMPORTANT): SBIT PASSED
2020-11-05T21:18:04.673Z,1604611084.673 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-11-05T21:18:04.673Z,1604611084.673 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-11-05T21:18:04.674Z,1604611084.674 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2020-11-05T21:18:04.675Z,1604611084.675 [SBIT](IMPORTANT): PAR_Licor.parCal=1.01146 none;

2020-11-05T21:18:04.675Z,1604611084.675 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-11-05T21:18:04.675Z,1604611084.675 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=256.318046 cubic_centimeter;

2020-11-05T21:18:04.675Z,1604611084.675 [SBIT](IMPORTANT): VerticalControl.massDefault=1.572083 millimeter;

2020-11-05T21:18:04.675Z,1604611084.675 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2020-11-05T21:18:05.069Z,1604611085.069 [MissionManager](IMPORTANT): Started mission Startup
2020-11-05T21:18:05.069Z,1604611085.069 [Startup] Running Loop=1
2020-11-05T21:18:05.070Z,1604611085.070 [Startup](DEBUG): Aggregate::initialize Startup
2020-11-05T21:18:05.070Z,1604611085.070 [Startup:A.GoToSurface] Running Loop=1
2020-11-05T21:18:05.070Z,1604611085.070 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-05T21:18:05.070Z,1604611085.070 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-05T21:18:05.071Z,1604611085.071 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-05T21:18:05.071Z,1604611085.071 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-05T21:18:05.072Z,1604611085.072 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-05T21:18:05.072Z,1604611085.072 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-05T21:18:05.074Z,1604611085.074 [Startup:StartupSatComms] Running Loop=1
2020-11-05T21:18:05.074Z,1604611085.074 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-11-05T21:18:05.074Z,1604611085.074 [Startup:StartupSatComms:A] Running Loop=1
2020-11-05T21:18:05.470Z,1604611085.470 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-11-05T21:19:05.276Z,1604611145.276 [Startup:StartupSatComms:A](INFO): Timed out from 2020-11-05T21:18:05.1Z
2020-11-05T21:19:05.276Z,1604611145.276 [Startup:StartupSatComms:A] Stopped
2020-11-05T21:19:05.277Z,1604611145.277 [Startup:StartupSatComms:B] Running Loop=1
2020-11-05T21:19:05.698Z,1604611145.698 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-11-05T21:19:13.310Z,1604611153.310 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004892
2020-11-05T21:19:20.747Z,1604611160.747 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20201105T210928/Courier0004.lzma
2020-11-05T21:19:21.749Z,1604611161.749 [DataOverHttps](INFO): Moved sent file to Logs/20201105T210928/Courier0004.lzma.bak
2020-11-05T21:19:21.749Z,1604611161.749 [DataOverHttps](INFO): SBD MOMSN=13100005
2020-11-05T21:19:37.256Z,1604611177.256 [DataOverHttps](INFO): Sending 452 bytes from file Logs/20201105T211634/Courier0000.lzma
2020-11-05T21:19:38.483Z,1604611178.483 [DataOverHttps](INFO): Moved sent file to Logs/20201105T211634/Courier0000.lzma.bak
2020-11-05T21:19:38.483Z,1604611178.483 [DataOverHttps](INFO): SBD MOMSN=13100011
2020-11-05T21:19:44.463Z,1604611184.463 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-11-05T21:19:44.463Z,1604611184.463 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-05T21:19:44.477Z,1604611184.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-05T21:19:44.886Z,1604611184.886 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-05T21:19:44.887Z,1604611184.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-11-05T21:19:54.069Z,1604611194.069 [DataOverHttps](INFO): Sending 1335 bytes from file Logs/20201105T210236/Express0001.lzma
2020-11-05T21:19:55.069Z,1604611195.069 [DataOverHttps](INFO): Moved sent file to Logs/20201105T210236/Express0001.lzma.bak
2020-11-05T21:19:55.070Z,1604611195.070 [DataOverHttps](INFO): SBD MOMSN=13100022
2020-11-05T21:20:05.461Z,1604611205.461 [Startup:StartupSatComms:B](INFO): Timed out from 2020-11-05T21:19:05.3Z
2020-11-05T21:20:05.461Z,1604611205.461 [Startup:StartupSatComms:B] Stopped
2020-11-05T21:20:05.461Z,1604611205.461 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-11-05T21:20:05.461Z,1604611205.461 [Startup:StartupSatComms] Stopped
2020-11-05T21:20:05.462Z,1604611205.462 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-11-05T21:20:05.462Z,1604611205.462 [Startup](INFO): Completed Startup
2020-11-05T21:20:05.462Z,1604611205.462 [MissionManager](INFO): Startup is completed.
2020-11-05T21:20:05.463Z,1604611205.463 [MissionManager](INFO): Uninitializing Mission Startup
2020-11-05T21:20:05.463Z,1604611205.463 [Startup] Stopped
2020-11-05T21:20:05.463Z,1604611205.463 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-11-05T21:20:05.463Z,1604611205.463 [Startup:A.GoToSurface] Stopped
2020-11-05T21:20:05.463Z,1604611205.463 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-05T21:20:05.876Z,1604611205.876 [MissionManager](IMPORTANT): Started mission Default
2020-11-05T21:20:05.876Z,1604611205.876 [Default] Running Loop=1
2020-11-05T21:20:05.877Z,1604611205.877 [Default](DEBUG): Aggregate::initialize Default
2020-11-05T21:20:05.877Z,1604611205.877 [Default:B.GoToSurface] Running Loop=1
2020-11-05T21:20:05.877Z,1604611205.877 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-05T21:20:05.877Z,1604611205.877 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-05T21:20:05.877Z,1604611205.877 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-05T21:20:05.878Z,1604611205.878 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-05T21:20:05.878Z,1604611205.878 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-05T21:20:05.878Z,1604611205.878 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-05T21:20:05.878Z,1604611205.878 [Default:A.Wait] Running Loop=1
2020-11-05T21:20:05.878Z,1604611205.878 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-11-05T21:20:10.765Z,1604611210.765 [DataOverHttps](INFO): Sending 898 bytes from file Logs/20201105T210236/Express0005.lzma
2020-11-05T21:20:11.765Z,1604611211.765 [DataOverHttps](INFO): Moved sent file to Logs/20201105T210236/Express0005.lzma.bak
2020-11-05T21:20:11.765Z,1604611211.765 [DataOverHttps](INFO): SBD MOMSN=13100073
2020-11-05T21:20:19.229Z,1604611219.229 [Default:A.Wait](INFO): Done Waiting.
2020-11-05T21:20:19.229Z,1604611219.229 [Default:A.Wait] Stopped
2020-11-05T21:20:19.229Z,1604611219.229 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-11-05T21:20:19.623Z,1604611219.623 [Default:CheckIn] Running Loop=1
2020-11-05T21:20:19.623Z,1604611219.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-05T21:20:19.623Z,1604611219.623 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-05T21:20:20.042Z,1604611220.042 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-11-05T21:22:26.457Z,1604611346.457 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-05T21:22:32.433Z,1604611352.433 [CommandLine](IMPORTANT): got command restart application
2020-11-05T21:22:33.443Z,1604611353.443 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:33.444Z,1604611353.444 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:33.483Z,1604611353.483 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-11-05T21:22:33.483Z,1604611353.483 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:33.484Z,1604611353.484 [CommandLine](INFO): Join timeout helper Thread ID is 1902
2020-11-05T21:22:33.484Z,1604611353.484 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-11-05T21:22:33.485Z,1604611353.485 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:33.485Z,1604611353.485 [NavChartDb](INFO): Join timeout helper Thread ID is 1903
2020-11-05T21:22:33.859Z,1604611353.859 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:33.860Z,1604611353.860 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:33.872Z,1604611353.872 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-11-05T21:22:33.873Z,1604611353.873 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:33.873Z,1604611353.873 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1904
2020-11-05T21:22:34.247Z,1604611354.247 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:34.247Z,1604611354.247 [WetLabsUBAT](INFO): Powering down
2020-11-05T21:22:34.248Z,1604611354.248 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:34.252Z,1604611354.252 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-11-05T21:22:34.252Z,1604611354.252 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:34.252Z,1604611354.252 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1905
2020-11-05T21:22:34.724Z,1604611354.724 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:34.724Z,1604611354.724 [WetLabsBB2FL](INFO): Powering down
2020-11-05T21:22:34.724Z,1604611354.724 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:34.731Z,1604611354.731 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-11-05T21:22:34.731Z,1604611354.731 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:34.732Z,1604611354.732 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1906
2020-11-05T21:22:34.775Z,1604611354.775 [CTD_Seabird](INFO): Powering down
2020-11-05T21:22:34.787Z,1604611354.787 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:34.787Z,1604611354.787 [CTD_Seabird](INFO): Powering down
2020-11-05T21:22:34.799Z,1604611354.799 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:34.816Z,1604611354.816 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-11-05T21:22:34.816Z,1604611354.816 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:34.817Z,1604611354.817 [BackSeatDriver](INFO): Join timeout helper Thread ID is 1907
2020-11-05T21:22:35.059Z,1604611355.059 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:35.060Z,1604611355.060 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:35.061Z,1604611355.061 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-11-05T21:22:35.061Z,1604611355.061 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:35.061Z,1604611355.061 [Radio_Surface](INFO): Join timeout helper Thread ID is 1908
2020-11-05T21:22:35.062Z,1604611355.062 [Radio_Surface](INFO): Powering down
2020-11-05T21:22:35.063Z,1604611355.063 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:35.071Z,1604611355.071 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:35.072Z,1604611355.072 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-11-05T21:22:35.073Z,1604611355.073 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:35.073Z,1604611355.073 [Onboard](INFO): Join timeout helper Thread ID is 1909
2020-11-05T21:22:35.449Z,1604611355.449 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-11-05T21:22:39.535Z,1604611359.535 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:39.536Z,1604611359.536 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:39.556Z,1604611359.556 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-11-05T21:22:39.556Z,1604611359.556 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:39.556Z,1604611359.556 [DataOverHttps](INFO): Join timeout helper Thread ID is 1910
2020-11-05T21:22:40.467Z,1604611360.467 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:40.468Z,1604611360.468 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.489Z,1604611360.489 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-11-05T21:22:40.489Z,1604611360.489 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.489Z,1604611360.489 [logger](INFO): Join timeout helper Thread ID is 1911
2020-11-05T21:22:40.492Z,1604611360.492 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:40.492Z,1604611360.492 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.494Z,1604611360.494 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-11-05T21:22:40.494Z,1604611360.494 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.495Z,1604611360.495 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-11-05T21:22:40.495Z,1604611360.495 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.495Z,1604611360.495 [controlThread](INFO): Join timeout helper Thread ID is 1912
2020-11-05T21:22:40.580Z,1604611360.580 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-05T21:22:40.581Z,1604611360.581 [controlThread](DEBUG): Uninitializing ControlThread
2020-11-05T21:22:40.582Z,1604611360.582 [AHRS_M2](INFO): Powering down
2020-11-05T21:22:40.652Z,1604611360.652 [NAL9602](INFO): Powering down
2020-11-05T21:22:40.653Z,1604611360.653 [DAT](INFO): Powering down
2020-11-05T21:22:40.773Z,1604611360.773 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-11-05T21:22:40.773Z,1604611360.773 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-11-05T21:22:40.774Z,1604611360.774 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-11-05T21:22:40.774Z,1604611360.774 [MissionManager](INFO): Uninitializing Mission Default
2020-11-05T21:22:40.774Z,1604611360.774 [Default] Stopped
2020-11-05T21:22:40.774Z,1604611360.774 [Default](DEBUG): Aggregate::uninitialize Default
2020-11-05T21:22:40.775Z,1604611360.775 [Default:B.GoToSurface] Stopped
2020-11-05T21:22:40.775Z,1604611360.775 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-05T21:22:40.775Z,1604611360.775 [Default:CheckIn] Stopped
2020-11-05T21:22:40.775Z,1604611360.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-05T21:22:40.775Z,1604611360.775 [Default:CheckIn:Read_GPS] Stopped
2020-11-05T21:22:40.777Z,1604611360.777 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-11-05T21:22:40.778Z,1604611360.778 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-11-05T21:22:40.778Z,1604611360.778 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-11-05T21:22:40.778Z,1604611360.778 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-11-05T21:22:40.778Z,1604611360.778 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-11-05T21:22:40.778Z,1604611360.778 [BuoyancyServo](INFO): Powering down
2020-11-05T21:22:40.792Z,1604611360.792 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-11-05T21:22:40.792Z,1604611360.792 [ElevatorServo](INFO): Powering down
2020-11-05T21:22:40.793Z,1604611360.793 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-11-05T21:22:40.793Z,1604611360.793 [MassServo](INFO): Powering down
2020-11-05T21:22:40.793Z,1604611360.793 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-05T21:22:40.794Z,1604611360.794 [RudderServo](INFO): Powering down
2020-11-05T21:22:40.794Z,1604611360.794 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-11-05T21:22:40.794Z,1604611360.794 [ThrusterServo](INFO): Powering down
2020-11-05T21:22:40.795Z,1604611360.795 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-11-05T21:22:40.796Z,1604611360.796 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-11-05T21:22:40.796Z,1604611360.796 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-11-05T21:22:40.796Z,1604611360.796 [CBIT](DEBUG): Powering off loads.
2020-11-05T21:22:40.807Z,1604611360.807 [CBIT](DEBUG): Disabling WDT.
2020-11-05T21:22:40.819Z,1604611360.819 [CBIT](DEBUG): Opening all GF detection circuits.
2020-11-05T21:22:40.820Z,1604611360.820 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.916Z,1604611360.916 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.918Z,1604611360.918 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.924Z,1604611360.924 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.981Z,1604611360.981 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.982Z,1604611360.982 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2020-11-05T21:22:40.982Z,1604611360.982 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-11-05T21:22:40.985Z,1604611360.985 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.988Z,1604611360.988 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:40.991Z,1604611360.991 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:41.001Z,1604611361.001 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-05T21:22:41.061Z,1604611361.061 [logger ThreadHandler](INFO): Thread cancelled.