2020-09-30T19:57:51.890Z,1601495871.890 [Supervisor](DEBUG): Initializing supervisor.
2020-09-30T19:57:51.893Z,1601495871.893 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-30T19:57:51.894Z,1601495871.894 [SyncHandler](INFO): Protected caller Thread ID is 6046
2020-09-30T19:57:51.895Z,1601495871.895 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-30T19:57:51.896Z,1601495871.896 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-30T19:57:51.896Z,1601495871.896 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6047
2020-09-30T19:57:51.899Z,1601495871.899 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-30T19:57:51.914Z,1601495871.914 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-30T19:57:51.915Z,1601495871.915 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-30T19:57:51.915Z,1601495871.915 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6048
2020-09-30T19:57:51.916Z,1601495871.916 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-30T19:57:51.917Z,1601495871.917 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-30T19:57:51.917Z,1601495871.917 [logger ThreadHandler](INFO): Protected caller Thread ID is 6049
2020-09-30T19:57:51.919Z,1601495871.919 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-30T19:57:51.919Z,1601495871.919 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-30T19:57:51.921Z,1601495871.921 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-30T19:57:52.013Z,1601495872.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-30T19:57:52.014Z,1601495872.014 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-30T19:57:52.567Z,1601495872.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-30T19:57:52.567Z,1601495872.567 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-30T19:57:52.755Z,1601495872.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-30T19:57:52.756Z,1601495872.756 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-30T19:57:52.852Z,1601495872.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-09-30T19:57:52.853Z,1601495872.853 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-30T19:57:53.132Z,1601495873.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-30T19:57:53.132Z,1601495873.132 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-30T19:57:53.265Z,1601495873.265 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-30T19:57:53.654Z,1601495873.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-30T19:57:53.654Z,1601495873.654 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-30T19:57:54.562Z,1601495874.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-30T19:57:54.563Z,1601495874.563 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-30T19:57:55.154Z,1601495875.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-30T19:57:55.154Z,1601495875.154 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-30T19:57:55.293Z,1601495875.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-30T19:57:55.294Z,1601495875.294 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-30T19:57:55.471Z,1601495875.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-30T19:57:55.471Z,1601495875.471 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-30T19:57:55.939Z,1601495875.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-30T19:57:55.939Z,1601495875.939 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-30T19:57:56.247Z,1601495876.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-30T19:57:56.247Z,1601495876.247 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-30T19:57:56.569Z,1601495876.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-30T19:57:56.570Z,1601495876.570 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-30T19:57:57.294Z,1601495877.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-30T19:57:57.294Z,1601495877.294 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-30T19:57:57.598Z,1601495877.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-30T19:57:57.600Z,1601495877.600 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-09-30T19:57:57.601Z,1601495877.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-09-30T19:57:57.683Z,1601495877.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-09-30T19:57:57.830Z,1601495877.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-09-30T19:57:57.935Z,1601495877.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-09-30T19:57:58.018Z,1601495878.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-09-30T19:57:58.110Z,1601495878.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-09-30T19:57:58.293Z,1601495878.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-09-30T19:57:58.515Z,1601495878.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-30T19:57:58.516Z,1601495878.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-09-30T19:57:58.607Z,1601495878.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-09-30T19:57:58.702Z,1601495878.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-09-30T19:57:58.827Z,1601495878.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-09-30T19:57:58.925Z,1601495878.925 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-09-30T19:57:58.925Z,1601495878.925 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-09-30T19:57:58.942Z,1601495878.942 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-30T19:57:59.018Z,1601495879.018 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-30T19:57:59.018Z,1601495879.018 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-30T19:57:59.064Z,1601495879.064 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-30T19:57:59.171Z,1601495879.171 [VerticalControl] Loaded
2020-09-30T19:57:59.171Z,1601495879.171 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-30T19:57:59.172Z,1601495879.172 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-30T19:57:59.248Z,1601495879.248 [HorizontalControl] Loaded
2020-09-30T19:57:59.248Z,1601495879.248 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-30T19:57:59.249Z,1601495879.249 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-30T19:57:59.254Z,1601495879.254 [SpeedControl] Loaded
2020-09-30T19:57:59.255Z,1601495879.255 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-30T19:57:59.255Z,1601495879.255 [LoopControl](DEBUG): Construct LoopControl.
2020-09-30T19:57:59.256Z,1601495879.256 [LoopControl] Loaded
2020-09-30T19:57:59.256Z,1601495879.256 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-30T19:57:59.256Z,1601495879.256 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-30T19:57:59.257Z,1601495879.257 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-30T19:57:59.296Z,1601495879.296 [DepthRateCalculator] Loaded
2020-09-30T19:57:59.297Z,1601495879.297 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-30T19:57:59.302Z,1601495879.302 [PitchRateCalculator] Loaded
2020-09-30T19:57:59.302Z,1601495879.302 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-30T19:57:59.316Z,1601495879.316 [SpeedCalculator] Loaded
2020-09-30T19:57:59.317Z,1601495879.317 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-30T19:57:59.337Z,1601495879.337 [TempGradientCalculator] Loaded
2020-09-30T19:57:59.337Z,1601495879.337 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-30T19:57:59.342Z,1601495879.342 [YawRateCalculator] Loaded
2020-09-30T19:57:59.342Z,1601495879.342 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-30T19:57:59.370Z,1601495879.370 [ElevatorOffsetCalculator] Loaded
2020-09-30T19:57:59.370Z,1601495879.370 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-30T19:57:59.371Z,1601495879.371 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-30T19:57:59.371Z,1601495879.371 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-30T19:57:59.397Z,1601495879.397 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-30T19:57:59.398Z,1601495879.398 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-30T19:57:59.481Z,1601495879.481 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-09-30T19:57:59.482Z,1601495879.482 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-30T19:57:59.764Z,1601495879.764 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-30T19:57:59.765Z,1601495879.765 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-30T19:57:59.861Z,1601495879.861 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-30T19:57:59.862Z,1601495879.862 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-30T19:58:00.189Z,1601495880.189 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-09-30T19:58:00.193Z,1601495880.193 [AHRS_M2](INFO): created writer for : platform_orientation
2020-09-30T19:58:00.195Z,1601495880.195 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-09-30T19:58:00.200Z,1601495880.200 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-09-30T19:58:00.201Z,1601495880.201 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-09-30T19:58:00.205Z,1601495880.205 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-09-30T19:58:00.206Z,1601495880.206 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-09-30T19:58:00.211Z,1601495880.211 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-09-30T19:58:00.280Z,1601495880.280 [AHRS_M2] Loaded
2020-09-30T19:58:00.280Z,1601495880.280 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-30T19:58:00.355Z,1601495880.355 [DataOverHttps] Loaded
2020-09-30T19:58:00.355Z,1601495880.355 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-30T19:58:00.356Z,1601495880.356 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-09-30T19:58:00.357Z,1601495880.357 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6131
2020-09-30T19:58:00.386Z,1601495880.386 [Depth_Keller] Loaded
2020-09-30T19:58:00.386Z,1601495880.386 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-30T19:58:00.495Z,1601495880.495 [NAL9602] Loaded
2020-09-30T19:58:00.495Z,1601495880.495 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-30T19:58:00.541Z,1601495880.541 [Onboard] Loaded
2020-09-30T19:58:00.542Z,1601495880.542 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-30T19:58:00.543Z,1601495880.543 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-09-30T19:58:00.543Z,1601495880.543 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6132
2020-09-30T19:58:00.562Z,1601495880.562 [Radio_Surface] Loaded
2020-09-30T19:58:00.562Z,1601495880.562 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-30T19:58:00.563Z,1601495880.563 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-09-30T19:58:00.563Z,1601495880.563 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6133
2020-09-30T19:58:00.710Z,1601495880.710 [DAT] Loaded
2020-09-30T19:58:00.710Z,1601495880.710 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-09-30T19:58:02.526Z,1601495882.526 [BPC1] Loaded
2020-09-30T19:58:02.527Z,1601495882.527 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-30T19:58:02.527Z,1601495882.527 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-30T19:58:02.528Z,1601495882.528 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-30T19:58:02.641Z,1601495882.641 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-30T19:58:02.641Z,1601495882.641 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-30T19:58:02.660Z,1601495882.660 [NavChart] Loaded
2020-09-30T19:58:02.661Z,1601495882.661 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-30T19:58:02.664Z,1601495882.664 [UniversalFixResidualReporter] Loaded
2020-09-30T19:58:02.665Z,1601495882.665 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-30T19:58:02.665Z,1601495882.665 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-30T19:58:02.666Z,1601495882.666 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-30T19:58:02.802Z,1601495882.802 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-30T19:58:02.813Z,1601495882.813 [SBIT] Loaded
2020-09-30T19:58:02.813Z,1601495882.813 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-30T19:58:02.814Z,1601495882.814 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-30T19:58:02.826Z,1601495882.826 [IBIT] Loaded
2020-09-30T19:58:02.826Z,1601495882.826 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-30T19:58:02.829Z,1601495882.829 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-30T19:58:02.965Z,1601495882.965 [CBIT] Loaded
2020-09-30T19:58:02.965Z,1601495882.965 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-30T19:58:02.966Z,1601495882.966 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-30T19:58:02.966Z,1601495882.966 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-30T19:58:03.086Z,1601495883.086 [BuoyancyServo] Loaded
2020-09-30T19:58:03.086Z,1601495883.086 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-30T19:58:03.114Z,1601495883.114 [ElevatorServo] Loaded
2020-09-30T19:58:03.114Z,1601495883.114 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-30T19:58:03.141Z,1601495883.141 [MassServo] Loaded
2020-09-30T19:58:03.141Z,1601495883.141 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-30T19:58:03.168Z,1601495883.168 [RudderServo] Loaded
2020-09-30T19:58:03.168Z,1601495883.168 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-30T19:58:03.194Z,1601495883.194 [ThrusterServo] Loaded
2020-09-30T19:58:03.194Z,1601495883.194 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-30T19:58:03.195Z,1601495883.195 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-30T19:58:03.195Z,1601495883.195 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-30T19:58:03.213Z,1601495883.213 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-30T19:58:03.214Z,1601495883.214 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-30T19:58:03.441Z,1601495883.441 [BackSeatDriver] Loaded
2020-09-30T19:58:03.442Z,1601495883.442 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-09-30T19:58:03.443Z,1601495883.443 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B054E0
2020-09-30T19:58:03.443Z,1601495883.443 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 6134
2020-09-30T19:58:03.470Z,1601495883.470 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-09-30T19:58:03.475Z,1601495883.475 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-09-30T19:58:03.476Z,1601495883.476 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-09-30T19:58:03.480Z,1601495883.480 [CTD_Seabird](INFO): created writer for : depth
2020-09-30T19:58:03.481Z,1601495883.481 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-09-30T19:58:03.486Z,1601495883.486 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-09-30T19:58:03.486Z,1601495883.486 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-09-30T19:58:03.492Z,1601495883.492 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-09-30T19:58:03.492Z,1601495883.492 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-09-30T19:58:03.497Z,1601495883.497 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-09-30T19:58:03.498Z,1601495883.498 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-09-30T19:58:03.504Z,1601495883.504 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-09-30T19:58:03.504Z,1601495883.504 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-09-30T19:58:03.509Z,1601495883.509 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-09-30T19:58:03.536Z,1601495883.536 [CTD_Seabird] Loaded
2020-09-30T19:58:03.536Z,1601495883.536 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-30T19:58:03.537Z,1601495883.537 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B354E0
2020-09-30T19:58:03.537Z,1601495883.537 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6135
2020-09-30T19:58:03.585Z,1601495883.585 [ESPComponent] Loaded
2020-09-30T19:58:03.585Z,1601495883.585 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-09-30T19:58:03.606Z,1601495883.606 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-09-30T19:58:03.607Z,1601495883.607 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-09-30T19:58:03.610Z,1601495883.610 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-09-30T19:58:03.611Z,1601495883.611 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-09-30T19:58:03.615Z,1601495883.615 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-09-30T19:58:03.615Z,1601495883.615 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-09-30T19:58:03.619Z,1601495883.619 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-09-30T19:58:03.619Z,1601495883.619 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-09-30T19:58:03.623Z,1601495883.623 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-09-30T19:58:03.623Z,1601495883.623 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-09-30T19:58:03.628Z,1601495883.628 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-09-30T19:58:03.628Z,1601495883.628 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-09-30T19:58:03.632Z,1601495883.632 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-09-30T19:58:03.632Z,1601495883.632 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-09-30T19:58:03.636Z,1601495883.636 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.640Z,1601495883.640 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.641Z,1601495883.641 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.641Z,1601495883.641 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.645Z,1601495883.645 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.645Z,1601495883.645 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.649Z,1601495883.649 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.650Z,1601495883.650 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T19:58:03.654Z,1601495883.654 [WetLabsBB2FL] Loaded
2020-09-30T19:58:03.654Z,1601495883.654 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-09-30T19:58:03.655Z,1601495883.655 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B654E0
2020-09-30T19:58:03.655Z,1601495883.655 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6136
2020-09-30T19:58:03.656Z,1601495883.656 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-30T19:58:03.660Z,1601495883.660 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-30T19:58:03.660Z,1601495883.660 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-30T19:58:03.667Z,1601495883.667 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-30T19:58:03.668Z,1601495883.668 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B954E0
2020-09-30T19:58:03.668Z,1601495883.668 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6137
2020-09-30T19:58:03.673Z,1601495883.673 [Supervisor](INFO): Main Thread ID is 4201
2020-09-30T19:58:03.673Z,1601495883.673 [Supervisor](DEBUG): Running supervisor.
2020-09-30T19:58:03.674Z,1601495883.674 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6138
2020-09-30T19:58:03.676Z,1601495883.676 [controlThread ThreadHandler](INFO): Handler Thread ID is 6139
2020-09-30T19:58:03.677Z,1601495883.677 [controlThread](DEBUG): Initializing ControlThread
2020-09-30T19:58:03.677Z,1601495883.677 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-30T19:58:03.679Z,1601495883.679 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-30T19:58:03.680Z,1601495883.680 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-30T19:58:03.680Z,1601495883.680 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-30T19:58:03.681Z,1601495883.681 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-30T19:58:03.681Z,1601495883.681 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-30T19:58:03.681Z,1601495883.681 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-30T19:58:03.682Z,1601495883.682 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-30T19:58:03.682Z,1601495883.682 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-30T19:58:03.683Z,1601495883.683 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-30T19:58:03.688Z,1601495883.688 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-30T19:58:03.688Z,1601495883.688 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-30T19:58:03.689Z,1601495883.689 [SBIT](INFO): Initialize SBIT Component.
2020-09-30T19:58:03.689Z,1601495883.689 [SBIT](IMPORTANT): git: 2020-09-25
2020-09-30T19:58:03.689Z,1601495883.689 [SBIT](INFO): git hash: 37975fc996eaab47e1849088435330a6c93ea7b4
2020-09-30T19:58:03.690Z,1601495883.690 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-30T19:58:03.691Z,1601495883.691 [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-09-30T19:58:03.692Z,1601495883.692 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2020-09-30T19:58:03.692Z,1601495883.692 [IBIT](INFO): Initialize IBIT Component.
2020-09-30T19:58:03.693Z,1601495883.693 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-30T19:58:03.694Z,1601495883.694 [logger ThreadHandler](INFO): Handler Thread ID is 6140
2020-09-30T19:58:03.707Z,1601495883.707 [CBIT](DEBUG): Initialized mux pins.
2020-09-30T19:58:03.707Z,1601495883.707 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-30T19:58:03.715Z,1601495883.715 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6141
2020-09-30T19:58:03.716Z,1601495883.716 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-30T19:58:03.727Z,1601495883.727 [Onboard ThreadHandler](INFO): Handler Thread ID is 6142
2020-09-30T19:58:03.731Z,1601495883.731 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-09-30T19:58:03.732Z,1601495883.732 [CBIT](DEBUG): Initializing heartbeat.
2020-09-30T19:58:03.745Z,1601495883.745 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6143
2020-09-30T19:58:03.767Z,1601495883.767 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 6144
2020-09-30T19:58:03.787Z,1601495883.787 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6145
2020-09-30T19:58:03.788Z,1601495883.788 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-30T19:58:03.792Z,1601495883.792 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6147
2020-09-30T19:58:03.793Z,1601495883.793 [WetLabsBB2FL](INFO): Powering down
2020-09-30T19:58:03.803Z,1601495883.803 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-30T19:58:03.803Z,1601495883.803 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-30T19:58:03.827Z,1601495883.827 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6148
2020-09-30T19:58:03.830Z,1601495883.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-30T19:58:03.830Z,1601495883.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-30T19:58:03.831Z,1601495883.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-30T19:58:03.831Z,1601495883.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-30T19:58:03.831Z,1601495883.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-30T19:58:03.831Z,1601495883.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-30T19:58:03.831Z,1601495883.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-30T19:58:03.831Z,1601495883.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-30T19:58:03.831Z,1601495883.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-30T19:58:03.832Z,1601495883.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-30T19:58:03.832Z,1601495883.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-30T19:58:03.832Z,1601495883.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-30T19:58:03.832Z,1601495883.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-30T19:58:03.832Z,1601495883.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-30T19:58:03.832Z,1601495883.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-30T19:58:03.832Z,1601495883.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-30T19:58:03.839Z,1601495883.839 [CBIT](DEBUG): Backplane powered.
2020-09-30T19:58:03.841Z,1601495883.841 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-30T19:58:03.855Z,1601495883.855 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-30T19:58:03.874Z,1601495883.874 [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-09-30T19:58:03.874Z,1601495883.874 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-30T19:58:03.950Z,1601495883.950 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-30T19:58:03.968Z,1601495883.968 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-30T19:58:03.988Z,1601495883.988 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-30T19:58:04.035Z,1601495884.035 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-30T19:58:04.037Z,1601495884.037 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-30T19:58:04.042Z,1601495884.042 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-30T19:58:04.054Z,1601495884.054 [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-09-30T19:58:04.058Z,1601495884.058 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,Depth_Keller,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-09-30T19:58:04.070Z,1601495884.070 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-30T19:58:04.151Z,1601495884.151 [Radio_Surface](INFO): Powering up
2020-09-30T19:58:04.203Z,1601495884.203 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-09-30T19:58:04.203Z,1601495884.203 [DAT](INFO): Powering up
2020-09-30T19:58:04.203Z,1601495884.203 [DAT](DEBUG): Initializing DAT.
2020-09-30T19:58:04.279Z,1601495884.279 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-09-30T19:58:04.302Z,1601495884.302 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-30T19:58:04.312Z,1601495884.312 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-30T19:58:04.313Z,1601495884.313 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-30T19:58:04.323Z,1601495884.323 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-30T19:58:04.324Z,1601495884.324 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-30T19:58:04.335Z,1601495884.335 [MassServo](DEBUG): Initializing MassServo.
2020-09-30T19:58:04.337Z,1601495884.337 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-30T19:58:04.347Z,1601495884.347 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-30T19:58:04.348Z,1601495884.348 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-30T19:58:04.359Z,1601495884.359 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-30T19:58:05.187Z,1601495885.187 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-30T19:58:05.187Z,1601495885.187 [RudderServo](FAULT): Rudder failed to initialize
2020-09-30T19:58:05.187Z,1601495885.187 [RudderServo] Communications Fault, FailCount= 1
2020-09-30T19:58:05.187Z,1601495885.187 [RudderServo](ERROR): Communications Fault
2020-09-30T19:58:05.298Z,1601495885.298 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-09-30T19:58:05.464Z,1601495885.464 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-30T19:58:05.464Z,1601495885.464 [RudderServo](INFO): Powering down
2020-09-30T19:58:06.147Z,1601495886.147 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-30T19:58:06.270Z,1601495886.270 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-30T19:58:06.274Z,1601495886.274 [CBIT](INFO): Clearing failed state for component RudderServo
2020-09-30T19:58:06.274Z,1601495886.274 [RudderServo] No Fault, FailCount= 1
2020-09-30T19:58:07.832Z,1601495887.832 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:08.212Z,1601495888.212 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:08.659Z,1601495888.659 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:09.039Z,1601495889.039 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:09.435Z,1601495889.435 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:09.835Z,1601495889.835 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:10.243Z,1601495890.243 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:10.639Z,1601495890.639 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:10.820Z,1601495890.820 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-09-30T19:58:11.043Z,1601495891.043 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:13.836Z,1601495893.836 [CBIT](CRITICAL): Environmental Failure. Press:14.477813 PSI. Humidity:57%. Temp:21 C. ABORTING MISSION
2020-09-30T19:58:13.836Z,1601495893.836 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2020-09-30T19:58:14.237Z,1601495894.237 [CommandLine](FAULT): Scheduling is paused
2020-09-30T19:58:14.238Z,1601495894.238 [CBIT](INFO): Critical error at 20200930T195813
2020-09-30T19:58:18.242Z,1601495898.242 [DAT](INFO): commRate: 800
2020-09-30T19:58:20.260Z,1601495900.260 [DAT](INFO): entering command mode
2020-09-30T19:58:20.664Z,1601495900.664 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:21.068Z,1601495901.068 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:21.472Z,1601495901.472 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:21.876Z,1601495901.876 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:22.280Z,1601495902.280 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:22.684Z,1601495902.684 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:23.088Z,1601495903.088 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:23.492Z,1601495903.492 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:23.896Z,1601495903.896 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:24.300Z,1601495904.300 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:24.704Z,1601495904.704 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:25.110Z,1601495905.110 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:25.512Z,1601495905.512 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:25.912Z,1601495905.912 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:26.316Z,1601495906.316 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:26.724Z,1601495906.724 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:27.124Z,1601495907.124 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:27.528Z,1601495907.528 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:27.936Z,1601495907.936 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:28.340Z,1601495908.340 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:28.740Z,1601495908.740 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:29.148Z,1601495909.148 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:29.552Z,1601495909.552 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:29.952Z,1601495909.952 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:30.364Z,1601495910.364 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:30.768Z,1601495910.768 [NAL9602](INFO): Powering up NAL9602
2020-09-30T19:58:30.770Z,1601495910.770 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:31.168Z,1601495911.168 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:31.572Z,1601495911.572 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:31.976Z,1601495911.976 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:32.376Z,1601495912.376 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:32.784Z,1601495912.784 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:33.188Z,1601495913.188 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:33.588Z,1601495913.588 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:33.996Z,1601495913.996 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:34.400Z,1601495914.400 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:34.447Z,1601495914.447 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:58:34.804Z,1601495914.804 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:34.815Z,1601495914.815 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:34.872Z,1601495914.872 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:58:35.215Z,1601495915.215 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:35.282Z,1601495915.282 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:58:35.612Z,1601495915.612 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T19:58:35.612Z,1601495915.612 [DAT](FAULT): failed to enter command mode
2020-09-30T19:58:35.688Z,1601495915.688 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:58:36.012Z,1601495916.012 [DAT](INFO): entering command mode
2020-09-30T19:58:36.067Z,1601495916.067 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:58:36.424Z,1601495916.424 [DAT](INFO): setting verbose to 3
2020-09-30T19:58:36.497Z,1601495916.497 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:58:36.825Z,1601495916.825 [DAT](INFO): set verbose to 3
2020-09-30T19:58:36.826Z,1601495916.826 [DAT](INFO): setting DatVerbose to 27440
2020-09-30T19:58:36.959Z,1601495916.959 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:36.991Z,1601495916.991 [CBIT](INFO): got buoyancy position: 968.947266
2020-09-30T19:58:37.225Z,1601495917.225 [DAT](INFO): set DatVerbose to 27440
2020-09-30T19:58:37.226Z,1601495917.226 [DAT](INFO): setting transmit power to 8
2020-09-30T19:58:37.311Z,1601495917.311 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:37.641Z,1601495917.641 [DAT](INFO): set transmit power to 8
2020-09-30T19:58:37.642Z,1601495917.642 [DAT](INFO): setting local address to 9
2020-09-30T19:58:37.728Z,1601495917.728 [BuoyancyServo](INFO): is needed
2020-09-30T19:58:38.039Z,1601495918.039 [DAT](INFO): set local address to 9
2020-09-30T19:58:41.672Z,1601495921.672 [NAL9602](INFO): NAL9602 initialized
2020-09-30T19:59:07.146Z,1601495947.146 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:07.539Z,1601495947.539 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:07.559Z,1601495947.559 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:07.956Z,1601495947.956 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:08.355Z,1601495948.355 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:08.762Z,1601495948.762 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:09.239Z,1601495949.239 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:09.244Z,1601495949.244 [CBIT](INFO): got buoyancy position: 970.961792
2020-09-30T19:59:09.595Z,1601495949.595 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:09.995Z,1601495949.995 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:39.464Z,1601495979.464 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:39.863Z,1601495979.863 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:39.868Z,1601495979.868 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:40.275Z,1601495980.275 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:40.685Z,1601495980.685 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:41.095Z,1601495981.095 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T19:59:41.543Z,1601495981.543 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:41.559Z,1601495981.559 [CBIT](INFO): got buoyancy position: 973.110596
2020-09-30T19:59:41.939Z,1601495981.939 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:42.331Z,1601495982.331 [BuoyancyServo](INFO): is needed
2020-09-30T19:59:43.132Z,1601495983.132 [SBIT](IMPORTANT): Beginning Startup BIT
2020-09-30T19:59:43.148Z,1601495983.148 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-30T19:59:54.447Z,1601495994.447 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.014413
CHAN A1 (24V): -0.000670
CHAN A2 (12V): -0.006838
CHAN A3 (5V): -0.003123
CHAN B0 (3.3V): 0.000046
CHAN B1 (3.15aV): -0.000273
CHAN B2 (3.15bV): -0.000593
CHAN B3 (GND): 0.002163
OPEN: 0.004537
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-30T20:00:11.860Z,1601496011.860 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:12.211Z,1601496012.211 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:12.255Z,1601496012.255 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:12.651Z,1601496012.651 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:13.034Z,1601496013.034 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:13.452Z,1601496013.452 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:13.865Z,1601496013.865 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:14.283Z,1601496014.283 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:14.403Z,1601496014.403 [CBIT](INFO): got buoyancy position: 973.244873
2020-09-30T20:00:14.653Z,1601496014.653 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:15.051Z,1601496015.051 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:15.463Z,1601496015.463 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:15.854Z,1601496015.854 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:16.251Z,1601496016.251 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:16.659Z,1601496016.659 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:17.051Z,1601496017.051 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:36.829Z,1601496036.829 [SBIT](IMPORTANT): SBIT PASSED
2020-09-30T20:00:36.829Z,1601496036.829 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-09-30T20:00:36.830Z,1601496036.830 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-09-30T20:00:36.830Z,1601496036.830 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-09-30T20:00:36.830Z,1601496036.830 [SBIT](IMPORTANT): BuoyancyServo.loadAtStartup=1 bool;

2020-09-30T20:00:36.830Z,1601496036.830 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-09-30T20:00:36.830Z,1601496036.830 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-09-30T20:00:36.847Z,1601496036.847 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-09-30T20:00:36.847Z,1601496036.847 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-09-30T20:00:36.847Z,1601496036.847 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-09-30T20:00:36.847Z,1601496036.847 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-09-30T20:00:36.847Z,1601496036.847 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-09-30T20:00:36.847Z,1601496036.847 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-09-30T20:00:36.847Z,1601496036.847 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-09-30T20:00:36.848Z,1601496036.848 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-09-30T20:00:36.848Z,1601496036.848 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-09-30T20:00:36.848Z,1601496036.848 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-09-30T20:00:36.848Z,1601496036.848 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-09-30T20:00:36.848Z,1601496036.848 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter;

2020-09-30T20:00:36.848Z,1601496036.848 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2020-09-30T20:00:36.848Z,1601496036.848 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-09-30T20:00:37.224Z,1601496037.224 [MissionManager](IMPORTANT): Started mission Startup
2020-09-30T20:00:37.224Z,1601496037.224 [Startup] Running Loop=1
2020-09-30T20:00:37.224Z,1601496037.224 [Startup](DEBUG): Aggregate::initialize Startup
2020-09-30T20:00:37.224Z,1601496037.224 [Startup:A.GoToSurface] Running Loop=1
2020-09-30T20:00:37.225Z,1601496037.225 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-30T20:00:37.225Z,1601496037.225 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-30T20:00:37.226Z,1601496037.226 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-30T20:00:37.226Z,1601496037.226 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-30T20:00:37.226Z,1601496037.226 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-30T20:00:37.227Z,1601496037.227 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-30T20:00:37.228Z,1601496037.228 [Startup:StartupSatComms] Running Loop=1
2020-09-30T20:00:37.228Z,1601496037.228 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-09-30T20:00:37.228Z,1601496037.228 [Startup:StartupSatComms:A] Running Loop=1
2020-09-30T20:00:37.636Z,1601496037.636 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-09-30T20:00:44.507Z,1601496044.507 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:44.924Z,1601496044.924 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:44.928Z,1601496044.928 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:45.343Z,1601496045.343 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:45.754Z,1601496045.754 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:46.144Z,1601496046.144 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:00:46.599Z,1601496046.599 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:46.603Z,1601496046.603 [CBIT](INFO): got buoyancy position: 970.961792
2020-09-30T20:00:46.959Z,1601496046.959 [BuoyancyServo](INFO): is needed
2020-09-30T20:00:47.359Z,1601496047.359 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:03.907Z,1601496063.907 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-09-30T20:01:03.907Z,1601496063.907 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T20:01:03.917Z,1601496063.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T20:01:04.313Z,1601496064.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T20:01:04.313Z,1601496064.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-09-30T20:01:16.860Z,1601496076.860 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:17.248Z,1601496077.248 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:17.252Z,1601496077.252 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:17.653Z,1601496077.653 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:18.056Z,1601496078.056 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:18.505Z,1601496078.505 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:19.031Z,1601496079.031 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:19.036Z,1601496079.036 [CBIT](INFO): got buoyancy position: 971.096069
2020-09-30T20:01:19.315Z,1601496079.315 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:19.708Z,1601496079.708 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:37.443Z,1601496097.443 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-30T20:00:37.2Z
2020-09-30T20:01:37.444Z,1601496097.444 [Startup:StartupSatComms:A] Stopped
2020-09-30T20:01:37.444Z,1601496097.444 [Startup:StartupSatComms:B] Running Loop=1
2020-09-30T20:01:37.834Z,1601496097.834 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-30T20:01:49.159Z,1601496109.159 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:49.552Z,1601496109.552 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:49.556Z,1601496109.556 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:49.964Z,1601496109.964 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:50.380Z,1601496110.380 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:50.760Z,1601496110.760 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:51.173Z,1601496111.173 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:01:51.647Z,1601496111.647 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:51.652Z,1601496111.652 [CBIT](INFO): got buoyancy position: 973.244873
2020-09-30T20:01:52.003Z,1601496112.003 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:52.407Z,1601496112.407 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:52.807Z,1601496112.807 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:53.227Z,1601496113.227 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:53.635Z,1601496113.635 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:54.055Z,1601496114.055 [BuoyancyServo](INFO): is needed
2020-09-30T20:01:54.443Z,1601496114.443 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:21.887Z,1601496141.887 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:22.272Z,1601496142.272 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:22.276Z,1601496142.276 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:22.681Z,1601496142.681 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:23.090Z,1601496143.090 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:23.495Z,1601496143.495 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:23.961Z,1601496143.961 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:23.965Z,1601496143.965 [CBIT](INFO): got buoyancy position: 971.096069
2020-09-30T20:02:24.323Z,1601496144.323 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:24.796Z,1601496144.796 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:37.636Z,1601496157.636 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-30T20:01:37.4Z
2020-09-30T20:02:37.636Z,1601496157.636 [Startup:StartupSatComms:B] Stopped
2020-09-30T20:02:37.636Z,1601496157.636 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-09-30T20:02:37.636Z,1601496157.636 [Startup:StartupSatComms] Stopped
2020-09-30T20:02:37.636Z,1601496157.636 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-09-30T20:02:37.637Z,1601496157.637 [Startup](INFO): Completed Startup
2020-09-30T20:02:37.637Z,1601496157.637 [MissionManager](INFO): Startup is completed.
2020-09-30T20:02:37.637Z,1601496157.637 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-30T20:02:37.637Z,1601496157.637 [Startup] Stopped
2020-09-30T20:02:37.637Z,1601496157.637 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-09-30T20:02:37.637Z,1601496157.637 [Startup:A.GoToSurface] Stopped
2020-09-30T20:02:37.638Z,1601496157.638 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-30T20:02:38.044Z,1601496158.044 [MissionManager](IMPORTANT): Started mission Default
2020-09-30T20:02:38.044Z,1601496158.044 [Default] Running Loop=1
2020-09-30T20:02:38.044Z,1601496158.044 [Default](DEBUG): Aggregate::initialize Default
2020-09-30T20:02:38.044Z,1601496158.044 [Default:B.GoToSurface] Running Loop=1
2020-09-30T20:02:38.044Z,1601496158.044 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-30T20:02:38.044Z,1601496158.044 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-30T20:02:38.045Z,1601496158.045 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-30T20:02:38.045Z,1601496158.045 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-30T20:02:38.045Z,1601496158.045 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-30T20:02:38.046Z,1601496158.046 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-30T20:02:38.046Z,1601496158.046 [Default:A.Wait] Running Loop=1
2020-09-30T20:02:38.046Z,1601496158.046 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-09-30T20:02:51.396Z,1601496171.396 [Default:A.Wait](INFO): Done Waiting.
2020-09-30T20:02:51.396Z,1601496171.396 [Default:A.Wait] Stopped
2020-09-30T20:02:51.396Z,1601496171.396 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-09-30T20:02:51.792Z,1601496171.792 [Default:CheckIn] Running Loop=1
2020-09-30T20:02:51.792Z,1601496171.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-30T20:02:51.792Z,1601496171.792 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-30T20:02:52.180Z,1601496172.180 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-09-30T20:02:54.193Z,1601496174.193 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:54.605Z,1601496174.605 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:54.609Z,1601496174.609 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:55.001Z,1601496175.001 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:55.399Z,1601496175.399 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:55.812Z,1601496175.812 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:02:56.283Z,1601496176.283 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:56.287Z,1601496176.287 [CBIT](INFO): got buoyancy position: 973.110596
2020-09-30T20:02:56.659Z,1601496176.659 [BuoyancyServo](INFO): is needed
2020-09-30T20:02:57.047Z,1601496177.047 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:26.552Z,1601496206.552 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:03:26.940Z,1601496206.940 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:26.945Z,1601496206.945 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:03:27.402Z,1601496207.402 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:03:27.752Z,1601496207.752 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:03:28.156Z,1601496208.156 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:03:28.562Z,1601496208.562 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:03:29.021Z,1601496209.021 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:29.025Z,1601496209.025 [CBIT](INFO): got buoyancy position: 973.110596
2020-09-30T20:03:29.371Z,1601496209.371 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:29.779Z,1601496209.779 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:30.175Z,1601496210.175 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:30.579Z,1601496210.579 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:30.983Z,1601496210.983 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:31.395Z,1601496211.395 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:31.791Z,1601496211.791 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:32.199Z,1601496212.199 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:44.680Z,1601496224.680 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-30T20:03:59.243Z,1601496239.243 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:03:59.636Z,1601496239.636 [BuoyancyServo](INFO): is needed
2020-09-30T20:03:59.656Z,1601496239.656 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:00.073Z,1601496240.073 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:00.469Z,1601496240.469 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:00.867Z,1601496240.867 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:01.335Z,1601496241.335 [BuoyancyServo](INFO): is needed
2020-09-30T20:04:01.340Z,1601496241.340 [CBIT](INFO): got buoyancy position: 971.096069
2020-09-30T20:04:01.687Z,1601496241.687 [BuoyancyServo](INFO): is needed
2020-09-30T20:04:02.103Z,1601496242.103 [BuoyancyServo](INFO): is needed
2020-09-30T20:04:04.883Z,1601496244.883 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-09-30T20:04:04.883Z,1601496244.883 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T20:04:04.893Z,1601496244.893 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T20:04:05.298Z,1601496245.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T20:04:05.298Z,1601496245.298 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-09-30T20:04:31.564Z,1601496271.564 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:31.964Z,1601496271.964 [BuoyancyServo](INFO): is needed
2020-09-30T20:04:31.973Z,1601496271.973 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:32.360Z,1601496272.360 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:32.805Z,1601496272.805 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:33.192Z,1601496273.192 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:04:33.659Z,1601496273.659 [BuoyancyServo](INFO): is needed
2020-09-30T20:04:33.663Z,1601496273.663 [CBIT](INFO): got buoyancy position: 971.096069
2020-09-30T20:04:34.007Z,1601496274.007 [BuoyancyServo](INFO): is needed
2020-09-30T20:04:34.411Z,1601496274.411 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:03.899Z,1601496303.899 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:04.276Z,1601496304.276 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:04.280Z,1601496304.280 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:04.688Z,1601496304.688 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:05.099Z,1601496305.099 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:05.620Z,1601496305.620 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:05.903Z,1601496305.903 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-09-30T20:05:05.907Z,1601496305.907 [BPC1](INFO): Received data from all battery sticks.
2020-09-30T20:05:06.003Z,1601496306.003 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:06.012Z,1601496306.012 [CBIT](INFO): got buoyancy position: 973.110596
2020-09-30T20:05:06.343Z,1601496306.343 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:06.743Z,1601496306.743 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:35.956Z,1601496335.956 [CommandLine](IMPORTANT): got command restart application
2020-09-30T20:05:36.219Z,1601496336.219 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:36.612Z,1601496336.612 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:36.637Z,1601496336.637 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:36.963Z,1601496336.963 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:36.963Z,1601496336.963 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.008Z,1601496337.008 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:37.159Z,1601496337.159 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-30T20:05:37.159Z,1601496337.159 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.159Z,1601496337.159 [CommandLine](INFO): Join timeout helper Thread ID is 6199
2020-09-30T20:05:37.160Z,1601496337.160 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-30T20:05:37.160Z,1601496337.160 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.161Z,1601496337.161 [NavChartDb](INFO): Join timeout helper Thread ID is 6200
2020-09-30T20:05:37.227Z,1601496337.227 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:37.227Z,1601496337.227 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.242Z,1601496337.242 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-09-30T20:05:37.242Z,1601496337.242 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.242Z,1601496337.242 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6201
2020-09-30T20:05:37.412Z,1601496337.412 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:37.631Z,1601496337.631 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:37.631Z,1601496337.631 [WetLabsBB2FL](INFO): Powering down
2020-09-30T20:05:37.632Z,1601496337.632 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.651Z,1601496337.651 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-09-30T20:05:37.651Z,1601496337.651 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.651Z,1601496337.651 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6202
2020-09-30T20:05:37.663Z,1601496337.663 [CTD_Seabird](INFO): Powering down
2020-09-30T20:05:37.675Z,1601496337.675 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:37.675Z,1601496337.675 [CTD_Seabird](INFO): Powering down
2020-09-30T20:05:37.687Z,1601496337.687 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.691Z,1601496337.691 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-09-30T20:05:37.691Z,1601496337.691 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.691Z,1601496337.691 [BackSeatDriver](INFO): Join timeout helper Thread ID is 6203
2020-09-30T20:05:37.832Z,1601496337.832 [CBIT](INFO): No valid buoyancy position, requesting data.
2020-09-30T20:05:37.895Z,1601496337.895 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:37.895Z,1601496337.895 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.900Z,1601496337.900 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-30T20:05:37.900Z,1601496337.900 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.900Z,1601496337.900 [Radio_Surface](INFO): Join timeout helper Thread ID is 6204
2020-09-30T20:05:37.943Z,1601496337.943 [Radio_Surface](INFO): Powering down
2020-09-30T20:05:37.944Z,1601496337.944 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:37.944Z,1601496337.944 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.959Z,1601496337.959 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-30T20:05:37.960Z,1601496337.960 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:37.960Z,1601496337.960 [Onboard](INFO): Join timeout helper Thread ID is 6205
2020-09-30T20:05:38.279Z,1601496338.279 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:38.284Z,1601496338.284 [CBIT](INFO): got buoyancy position: 973.244873
2020-09-30T20:05:38.587Z,1601496338.587 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-09-30T20:05:38.643Z,1601496338.643 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:39.059Z,1601496339.059 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:39.091Z,1601496339.091 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:39.091Z,1601496339.091 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:39.111Z,1601496339.111 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-30T20:05:39.112Z,1601496339.112 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:39.112Z,1601496339.112 [DataOverHttps](INFO): Join timeout helper Thread ID is 6206
2020-09-30T20:05:39.475Z,1601496339.475 [BuoyancyServo](INFO): is needed
2020-09-30T20:05:39.591Z,1601496339.591 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:39.591Z,1601496339.591 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:39.595Z,1601496339.595 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-30T20:05:39.596Z,1601496339.596 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:39.596Z,1601496339.596 [logger](INFO): Join timeout helper Thread ID is 6207
2020-09-30T20:05:39.639Z,1601496339.639 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:39.639Z,1601496339.639 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:39.655Z,1601496339.655 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-30T20:05:39.656Z,1601496339.656 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:39.656Z,1601496339.656 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-30T20:05:39.656Z,1601496339.656 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:39.656Z,1601496339.656 [controlThread](INFO): Join timeout helper Thread ID is 6208
2020-09-30T20:05:39.799Z,1601496339.799 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T20:05:39.799Z,1601496339.799 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-30T20:05:39.799Z,1601496339.799 [AHRS_M2](INFO): Powering down
2020-09-30T20:05:39.871Z,1601496339.871 [NAL9602](INFO): Powering down
2020-09-30T20:05:39.872Z,1601496339.872 [DAT](INFO): Powering down
2020-09-30T20:05:39.993Z,1601496339.993 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-30T20:05:39.994Z,1601496339.994 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-30T20:05:39.994Z,1601496339.994 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-30T20:05:39.995Z,1601496339.995 [MissionManager](INFO): Uninitializing Mission Default
2020-09-30T20:05:39.995Z,1601496339.995 [Default] Stopped
2020-09-30T20:05:39.995Z,1601496339.995 [Default](DEBUG): Aggregate::uninitialize Default
2020-09-30T20:05:39.995Z,1601496339.995 [Default:B.GoToSurface] Stopped
2020-09-30T20:05:39.995Z,1601496339.995 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-30T20:05:39.995Z,1601496339.995 [Default:CheckIn] Stopped
2020-09-30T20:05:39.995Z,1601496339.995 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-30T20:05:39.996Z,1601496339.996 [Default:CheckIn:Read_GPS] Stopped
2020-09-30T20:05:39.998Z,1601496339.998 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-30T20:05:39.998Z,1601496339.998 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-30T20:05:39.998Z,1601496339.998 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-30T20:05:39.999Z,1601496339.999 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-30T20:05:39.999Z,1601496339.999 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-30T20:05:39.999Z,1601496339.999 [BuoyancyServo](INFO): Powering down
2020-09-30T20:05:40.131Z,1601496340.131 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-30T20:05:40.131Z,1601496340.131 [ElevatorServo](INFO): Powering down
2020-09-30T20:05:40.132Z,1601496340.132 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-30T20:05:40.132Z,1601496340.132 [MassServo](INFO): Powering down
2020-09-30T20:05:40.133Z,1601496340.133 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-30T20:05:40.133Z,1601496340.133 [RudderServo](INFO): Powering down
2020-09-30T20:05:40.134Z,1601496340.134 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-30T20:05:40.134Z,1601496340.134 [ThrusterServo](INFO): Powering down
2020-09-30T20:05:40.134Z,1601496340.134 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-30T20:05:40.135Z,1601496340.135 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-30T20:05:40.135Z,1601496340.135 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-30T20:05:40.135Z,1601496340.135 [CBIT](DEBUG): Powering off loads.
2020-09-30T20:05:40.147Z,1601496340.147 [CBIT](DEBUG): Disabling WDT.
2020-09-30T20:05:40.159Z,1601496340.159 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-30T20:05:40.159Z,1601496340.159 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.257Z,1601496340.257 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.259Z,1601496340.259 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.264Z,1601496340.264 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.324Z,1601496340.324 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.328Z,1601496340.328 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.331Z,1601496340.331 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.341Z,1601496340.341 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T20:05:40.402Z,1601496340.402 [logger ThreadHandler](INFO): Thread cancelled.