2022-05-25T19:46:49.575Z,1653508009.575 [Supervisor](DEBUG): Initializing supervisor.
2022-05-25T19:46:49.580Z,1653508009.580 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-05-25T19:46:49.580Z,1653508009.580 [SyncHandler](INFO): Protected caller Thread ID is 1407
2022-05-25T19:46:49.581Z,1653508009.581 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-05-25T19:46:49.582Z,1653508009.582 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-05-25T19:46:49.582Z,1653508009.582 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1408
2022-05-25T19:46:49.586Z,1653508009.586 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-05-25T19:46:49.604Z,1653508009.604 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-05-25T19:46:49.605Z,1653508009.605 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-05-25T19:46:49.605Z,1653508009.605 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1409
2022-05-25T19:46:49.609Z,1653508009.609 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-05-25T19:46:49.610Z,1653508009.610 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-05-25T19:46:49.611Z,1653508009.611 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1410
2022-05-25T19:46:49.613Z,1653508009.613 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-05-25T19:46:49.614Z,1653508009.614 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-05-25T19:46:49.614Z,1653508009.614 [logger ThreadHandler](INFO): Protected caller Thread ID is 1411
2022-05-25T19:46:49.618Z,1653508009.618 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-05-25T19:46:49.618Z,1653508009.618 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-05-25T19:46:49.620Z,1653508009.620 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-05-25T19:46:49.713Z,1653508009.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-05-25T19:46:49.713Z,1653508009.713 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-05-25T19:46:49.926Z,1653508009.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-05-25T19:46:49.927Z,1653508009.927 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-05-25T19:46:50.185Z,1653508010.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-05-25T19:46:50.186Z,1653508010.186 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-05-25T19:46:50.291Z,1653508010.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-05-25T19:46:50.292Z,1653508010.292 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-05-25T19:46:51.013Z,1653508011.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-05-25T19:46:51.014Z,1653508011.014 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-05-25T19:46:51.680Z,1653508011.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-05-25T19:46:51.681Z,1653508011.681 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-05-25T19:46:52.251Z,1653508012.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-05-25T19:46:52.252Z,1653508012.252 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-05-25T19:46:52.629Z,1653508012.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-05-25T19:46:52.630Z,1653508012.630 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-05-25T19:46:52.954Z,1653508012.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-05-25T19:46:52.955Z,1653508012.955 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-05-25T19:46:53.323Z,1653508013.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-05-25T19:46:53.324Z,1653508013.324 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-05-25T19:46:53.542Z,1653508013.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-05-25T19:46:53.542Z,1653508013.542 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-05-25T19:46:53.671Z,1653508013.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-05-25T19:46:53.671Z,1653508013.671 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-05-25T19:46:53.751Z,1653508013.751 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-05-25T19:46:53.828Z,1653508013.828 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-05-25T19:46:54.013Z,1653508014.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-05-25T19:46:54.013Z,1653508014.013 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-05-25T19:46:54.229Z,1653508014.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-05-25T19:46:54.231Z,1653508014.231 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2022-05-25T19:46:54.232Z,1653508014.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2022-05-25T19:46:54.313Z,1653508014.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2022-05-25T19:46:54.614Z,1653508014.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-05-25T19:46:54.614Z,1653508014.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2022-05-25T19:46:54.897Z,1653508014.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2022-05-25T19:46:55.340Z,1653508015.340 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2022-05-25T19:46:55.551Z,1653508015.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2022-05-25T19:46:55.634Z,1653508015.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2022-05-25T19:46:55.740Z,1653508015.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2022-05-25T19:46:55.844Z,1653508015.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2022-05-25T19:46:56.001Z,1653508016.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2022-05-25T19:46:56.103Z,1653508016.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2022-05-25T19:46:56.202Z,1653508016.202 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2022-05-25T19:46:56.202Z,1653508016.202 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-05-25T19:46:56.221Z,1653508016.221 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-05-25T19:46:56.341Z,1653508016.341 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-05-25T19:46:56.342Z,1653508016.342 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-05-25T19:46:56.478Z,1653508016.478 [BuoyancyServo] Loaded
2022-05-25T19:46:56.478Z,1653508016.478 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-05-25T19:46:56.499Z,1653508016.499 [ElevatorServo] Loaded
2022-05-25T19:46:56.499Z,1653508016.499 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-05-25T19:46:56.518Z,1653508016.518 [RudderServo] Loaded
2022-05-25T19:46:56.518Z,1653508016.518 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-05-25T19:46:56.535Z,1653508016.535 [ThrusterHE] Loaded
2022-05-25T19:46:56.535Z,1653508016.535 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-05-25T19:46:56.535Z,1653508016.535 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-05-25T19:46:56.536Z,1653508016.536 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-05-25T19:46:56.693Z,1653508016.693 [SBIT](DEBUG): Construct Startup Built In Test.
2022-05-25T19:46:56.708Z,1653508016.708 [SBIT] Loaded
2022-05-25T19:46:56.708Z,1653508016.708 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-05-25T19:46:56.710Z,1653508016.710 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-05-25T19:46:56.731Z,1653508016.731 [IBIT] Loaded
2022-05-25T19:46:56.731Z,1653508016.731 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-05-25T19:46:56.737Z,1653508016.737 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-05-25T19:46:56.950Z,1653508016.950 [CBIT] Loaded
2022-05-25T19:46:56.951Z,1653508016.951 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-05-25T19:46:56.951Z,1653508016.951 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-05-25T19:46:56.952Z,1653508016.952 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-05-25T19:46:57.131Z,1653508017.131 [CTD_Seabird] Loaded
2022-05-25T19:46:57.131Z,1653508017.131 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-05-25T19:46:57.132Z,1653508017.132 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-05-25T19:46:57.132Z,1653508017.132 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1494
2022-05-25T19:46:57.163Z,1653508017.163 [ESPComponent] Loaded
2022-05-25T19:46:57.163Z,1653508017.163 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-05-25T19:46:57.182Z,1653508017.182 [PAR_Licor] Loaded
2022-05-25T19:46:57.183Z,1653508017.183 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-05-25T19:46:57.213Z,1653508017.213 [WetLabsBB2FL] Loaded
2022-05-25T19:46:57.213Z,1653508017.213 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-05-25T19:46:57.214Z,1653508017.214 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0
2022-05-25T19:46:57.215Z,1653508017.215 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1495
2022-05-25T19:46:57.216Z,1653508017.216 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-05-25T19:46:57.216Z,1653508017.216 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-05-25T19:46:57.270Z,1653508017.270 [DepthRateCalculator] Loaded
2022-05-25T19:46:57.271Z,1653508017.271 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-05-25T19:46:57.276Z,1653508017.276 [PitchRateCalculator] Loaded
2022-05-25T19:46:57.276Z,1653508017.276 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-05-25T19:46:57.286Z,1653508017.286 [SpeedCalculator] Loaded
2022-05-25T19:46:57.286Z,1653508017.286 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-05-25T19:46:57.291Z,1653508017.291 [YawRateCalculator] Loaded
2022-05-25T19:46:57.291Z,1653508017.291 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-05-25T19:46:57.309Z,1653508017.309 [ElevatorOffsetCalculator] Loaded
2022-05-25T19:46:57.310Z,1653508017.310 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-05-25T19:46:57.326Z,1653508017.326 [ShortestDistanceToPolygonSidesCalculator] Loaded
2022-05-25T19:46:57.326Z,1653508017.326 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2022-05-25T19:46:57.326Z,1653508017.326 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-05-25T19:46:57.327Z,1653508017.327 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-05-25T19:46:57.433Z,1653508017.433 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-05-25T19:46:57.434Z,1653508017.434 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-05-25T19:46:57.463Z,1653508017.463 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-05-25T19:46:57.463Z,1653508017.463 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-05-25T19:46:57.577Z,1653508017.577 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-05-25T19:46:57.577Z,1653508017.577 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-05-25T19:46:57.590Z,1653508017.590 [NavChart] Loaded
2022-05-25T19:46:57.590Z,1653508017.590 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-05-25T19:46:57.596Z,1653508017.596 [UniversalFixResidualReporter] Loaded
2022-05-25T19:46:57.596Z,1653508017.596 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-05-25T19:46:57.597Z,1653508017.597 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-05-25T19:46:57.597Z,1653508017.597 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-05-25T19:46:58.024Z,1653508018.024 [AHRS_M2] Loaded
2022-05-25T19:46:58.024Z,1653508018.024 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-05-25T19:46:58.058Z,1653508018.058 [BackseatComponent] Loaded
2022-05-25T19:46:58.059Z,1653508018.059 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-05-25T19:46:58.060Z,1653508018.060 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0
2022-05-25T19:46:58.060Z,1653508018.060 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1496
2022-05-25T19:46:58.063Z,1653508018.063 [LcmUniversalReporter] Loaded
2022-05-25T19:46:58.063Z,1653508018.063 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-05-25T19:46:58.138Z,1653508018.138 [DataOverHttps] Loaded
2022-05-25T19:46:58.138Z,1653508018.138 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-05-25T19:46:58.139Z,1653508018.139 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A3D4E0
2022-05-25T19:46:58.139Z,1653508018.139 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1497
2022-05-25T19:46:58.159Z,1653508018.159 [Depth_Keller] Loaded
2022-05-25T19:46:58.159Z,1653508018.159 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-05-25T19:46:58.164Z,1653508018.164 [DropWeight] Loaded
2022-05-25T19:46:58.164Z,1653508018.164 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-05-25T19:46:58.201Z,1653508018.201 [DVL_micro] Loaded
2022-05-25T19:46:58.201Z,1653508018.201 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2022-05-25T19:46:58.260Z,1653508018.260 [NAL9602] Loaded
2022-05-25T19:46:58.260Z,1653508018.260 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-05-25T19:46:58.286Z,1653508018.286 [Onboard] Loaded
2022-05-25T19:46:58.286Z,1653508018.286 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-05-25T19:46:58.287Z,1653508018.287 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A6D4E0
2022-05-25T19:46:58.288Z,1653508018.288 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1498
2022-05-25T19:46:58.299Z,1653508018.299 [Power24vConverter] Loaded
2022-05-25T19:46:58.300Z,1653508018.300 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-05-25T19:46:58.313Z,1653508018.313 [Radio_Surface] Loaded
2022-05-25T19:46:58.313Z,1653508018.313 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-05-25T19:46:58.314Z,1653508018.314 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A9D4E0
2022-05-25T19:46:58.314Z,1653508018.314 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1499
2022-05-25T19:46:58.315Z,1653508018.315 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-05-25T19:46:58.316Z,1653508018.316 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-05-25T19:46:58.379Z,1653508018.379 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-05-25T19:46:58.379Z,1653508018.379 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-05-25T19:46:58.435Z,1653508018.435 [VerticalControl](DEBUG): Construct VerticalControl.
2022-05-25T19:46:58.486Z,1653508018.486 [VerticalControl] Loaded
2022-05-25T19:46:58.487Z,1653508018.487 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-05-25T19:46:58.490Z,1653508018.490 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-05-25T19:46:58.524Z,1653508018.524 [HorizontalControl] Loaded
2022-05-25T19:46:58.525Z,1653508018.525 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-05-25T19:46:58.527Z,1653508018.527 [SpeedControl](DEBUG): Construct SpeedControl.
2022-05-25T19:46:58.529Z,1653508018.529 [SpeedControl] Loaded
2022-05-25T19:46:58.529Z,1653508018.529 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-05-25T19:46:58.532Z,1653508018.532 [LoopControl](DEBUG): Construct LoopControl.
2022-05-25T19:46:58.532Z,1653508018.532 [LoopControl] Loaded
2022-05-25T19:46:58.532Z,1653508018.532 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-05-25T19:46:58.533Z,1653508018.533 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-05-25T19:46:58.533Z,1653508018.533 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-05-25T19:46:58.544Z,1653508018.544 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-05-25T19:46:58.545Z,1653508018.545 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-05-25T19:46:58.891Z,1653508018.891 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-05-25T19:46:58.897Z,1653508018.897 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-05-25T19:46:58.899Z,1653508018.899 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-05-25T19:46:58.910Z,1653508018.910 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-05-25T19:46:58.911Z,1653508018.911 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA74E0
2022-05-25T19:46:58.911Z,1653508018.911 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1500
2022-05-25T19:46:58.916Z,1653508018.916 [Supervisor](INFO): Main Thread ID is 1406
2022-05-25T19:46:58.916Z,1653508018.916 [Supervisor](DEBUG): Running supervisor.
2022-05-25T19:46:58.916Z,1653508018.916 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1501
2022-05-25T19:46:58.917Z,1653508018.917 [CommandExec](INFO): Initializing the command executive.
2022-05-25T19:46:58.918Z,1653508018.918 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1502
2022-05-25T19:46:58.921Z,1653508018.921 [controlThread ThreadHandler](INFO): Handler Thread ID is 1503
2022-05-25T19:46:58.921Z,1653508018.921 [controlThread](DEBUG): Initializing ControlThread
2022-05-25T19:46:58.923Z,1653508018.923 [SBIT](INFO): Initialize SBIT Component.
2022-05-25T19:46:58.923Z,1653508018.923 [SBIT](IMPORTANT): git: 2022-05-16
2022-05-25T19:46:58.923Z,1653508018.923 [SBIT](INFO): git hash: 22dd8ec1d68d2d3458ffb0a0481759ba9019d0f5
2022-05-25T19:46:58.924Z,1653508018.924 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-05-25T19:46:58.925Z,1653508018.925 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-05-25T19:46:58.926Z,1653508018.926 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2022-05-25T19:46:58.926Z,1653508018.926 [IBIT](INFO): Initialize IBIT Component.
2022-05-25T19:46:58.927Z,1653508018.927 [CBIT](DEBUG): Initialize CBIT Component.
2022-05-25T19:46:58.928Z,1653508018.928 [logger ThreadHandler](INFO): Handler Thread ID is 1504
2022-05-25T19:46:58.940Z,1653508018.940 [CBIT](DEBUG): Initialized mux pins.
2022-05-25T19:46:58.940Z,1653508018.940 [CBIT](DEBUG): Initializing the watchdog timer.
2022-05-25T19:46:58.948Z,1653508018.948 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1505
2022-05-25T19:46:58.949Z,1653508018.949 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-25T19:46:58.953Z,1653508018.953 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1507
2022-05-25T19:46:58.956Z,1653508018.956 [WetLabsBB2FL](INFO): Powering up
2022-05-25T19:46:58.958Z,1653508018.958 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1509
2022-05-25T19:46:58.964Z,1653508018.964 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-05-25T19:46:58.964Z,1653508018.964 [CBIT](DEBUG): Initializing heartbeat.
2022-05-25T19:46:58.965Z,1653508018.965 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1510
2022-05-25T19:46:58.966Z,1653508018.966 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-05-25T19:46:58.976Z,1653508018.976 [Onboard ThreadHandler](INFO): Handler Thread ID is 1511
2022-05-25T19:46:58.994Z,1653508018.994 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1512
2022-05-25T19:46:59.012Z,1653508019.012 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1513
2022-05-25T19:46:59.022Z,1653508019.022 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-05-25T19:46:59.023Z,1653508019.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-05-25T19:46:59.023Z,1653508019.023 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-05-25T19:46:59.023Z,1653508019.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-05-25T19:46:59.023Z,1653508019.023 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-05-25T19:46:59.023Z,1653508019.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-05-25T19:46:59.024Z,1653508019.024 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-05-25T19:46:59.024Z,1653508019.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-05-25T19:46:59.024Z,1653508019.024 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2022-05-25T19:46:59.024Z,1653508019.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2022-05-25T19:46:59.036Z,1653508019.036 [CBIT](DEBUG): Deactivating GF circuits.
2022-05-25T19:46:59.036Z,1653508019.036 [CBIT](DEBUG): Deactivating emergency mode.
2022-05-25T19:46:59.072Z,1653508019.072 [CBIT](DEBUG): Backplane powered.
2022-05-25T19:46:59.076Z,1653508019.076 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-05-25T19:46:59.077Z,1653508019.077 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-05-25T19:46:59.077Z,1653508019.077 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-05-25T19:46:59.077Z,1653508019.077 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-05-25T19:46:59.078Z,1653508019.078 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-05-25T19:46:59.078Z,1653508019.078 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2022-05-25T19:46:59.079Z,1653508019.079 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-05-25T19:46:59.080Z,1653508019.080 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-05-25T19:46:59.090Z,1653508019.090 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-05-25T19:46:59.091Z,1653508019.091 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-05-25T19:46:59.100Z,1653508019.100 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-05-25T19:46:59.101Z,1653508019.101 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-05-25T19:46:59.102Z,1653508019.102 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-05-25T19:46:59.139Z,1653508019.139 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-25T19:46:59.186Z,1653508019.186 [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>


2022-05-25T19:46:59.187Z,1653508019.187 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-05-25T19:46:59.273Z,1653508019.273 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-05-25T19:46:59.274Z,1653508019.274 [Default:A.Wait](DEBUG): Construct Wait.
2022-05-25T19:46:59.276Z,1653508019.276 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-25T19:46:59.316Z,1653508019.316 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-05-25T19:46:59.319Z,1653508019.319 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-05-25T19:46:59.337Z,1653508019.337 [Default:E.Execute](DEBUG): Construct Execute.
2022-05-25T19:46:59.357Z,1653508019.357 [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>


2022-05-25T19:46:59.361Z,1653508019.361 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-05-25T19:46:59.392Z,1653508019.392 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-25T19:46:59.400Z,1653508019.400 [Radio_Surface](INFO): Powering up
2022-05-25T19:46:59.458Z,1653508019.458 [Power24vConverter](INFO): Powering up.
2022-05-25T19:46:59.526Z,1653508019.526 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-05-25T19:46:59.532Z,1653508019.532 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-05-25T19:46:59.533Z,1653508019.533 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-25T19:46:59.541Z,1653508019.541 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-25T19:46:59.541Z,1653508019.541 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-25T19:46:59.548Z,1653508019.548 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-25T19:46:59.549Z,1653508019.549 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-05-25T19:46:59.556Z,1653508019.556 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-05-25T19:46:59.817Z,1653508019.817 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-05-25T19:46:59.817Z,1653508019.817 [DropWeight] Hardware Fault, FailCount= 1
2022-05-25T19:46:59.817Z,1653508019.817 [DropWeight](ERROR): Hardware Fault
2022-05-25T19:46:59.869Z,1653508019.869 [CommandExec](FAULT): Scheduling is paused
2022-05-25T19:46:59.869Z,1653508019.869 [CBIT](INFO): Critical error at 20220525T194659
2022-05-25T19:46:59.869Z,1653508019.869 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-05-25T19:46:59.884Z,1653508019.884 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-05-25T19:46:59.884Z,1653508019.884 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-05-25T19:47:00.257Z,1653508020.257 [CBIT](INFO): Critical error at 20220525T194659
2022-05-25T19:47:01.064Z,1653508021.064 [WetLabsBB2FL](INFO): Powering down
2022-05-25T19:47:05.241Z,1653508025.241 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-05-25T19:47:05.981Z,1653508025.981 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-05-25T19:47:09.130Z,1653508029.130 [CBIT](CRITICAL): Environmental Failure. Press:14.643825 PSI. Humidity:39%. Temp:23 C. ABORTING MISSION
2022-05-25T19:47:09.511Z,1653508029.511 [CBIT](INFO): Critical error at 20220525T194709
2022-05-25T19:47:21.662Z,1653508041.662 [CommandExec](IMPORTANT): got command get platform_orientation degree
2022-05-25T19:47:21.663Z,1653508041.663 [CommandExec](IMPORTANT): platform_orientation 268.278629 arcdeg
2022-05-25T19:47:28.479Z,1653508048.479 [NAL9602](INFO): Powering up NAL9602
2022-05-25T19:47:39.378Z,1653508059.378 [NAL9602](INFO): NAL9602 initialized
2022-05-25T19:47:39.457Z,1653508059.457 [CommandExec](IMPORTANT): got command gfscan
2022-05-25T19:47:39.803Z,1653508059.803 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-25T19:47:50.719Z,1653508070.719 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007183
CHAN A1 (24V): 0.001996
CHAN A2 (12V): -0.005163
CHAN A3 (5V): -0.002408
CHAN B0 (3.3V): -0.000948
CHAN B1 (3.15aV): -0.001184
CHAN B2 (3.15bV): -0.001185
CHAN B3 (GND): 0.000168
OPEN: 0.004917
Full Scale: +/- 1 mA
2022-05-25T19:47:58.387Z,1653508078.387 [SBIT](IMPORTANT): Beginning Startup BIT
2022-05-25T19:47:58.391Z,1653508078.391 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-25T19:48:09.321Z,1653508089.321 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001589
CHAN A1 (24V): 0.002134
CHAN A2 (12V): -0.004169
CHAN A3 (5V): -0.002451
CHAN B0 (3.3V): -0.001292
CHAN B1 (3.15aV): -0.003177
CHAN B2 (3.15bV): -0.001853
CHAN B3 (GND): 0.000012
OPEN: 0.004903
Full Scale: +/- 1 mA
2022-05-25T19:48:11.711Z,1653508091.711 [SBIT](ERROR): Could not read massPosReader_.
2022-05-25T19:48:38.371Z,1653508118.371 [SBIT](ERROR): Could not read massPosReader_.
2022-05-25T19:48:38.371Z,1653508118.371 [SBIT](FAULT): Control surface position failure.
2022-05-25T19:48:47.814Z,1653508127.814 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008876
2022-05-25T19:48:52.147Z,1653508132.147 [SBIT](ERROR): Could not read massPosReader_.
2022-05-25T19:48:52.147Z,1653508132.147 [SBIT](FAULT): Control surface position failure.
2022-05-25T19:48:52.517Z,1653508132.517 [SBIT](CRITICAL): SBIT FAILED
2022-05-25T19:48:52.517Z,1653508132.517 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-05-25T19:48:52.518Z,1653508132.518 [SBIT](IMPORTANT): BPC1.loadAtStartup=0 bool;

2022-05-25T19:48:52.518Z,1653508132.518 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2022-05-25T19:48:52.518Z,1653508132.518 [SBIT](IMPORTANT): Depth_Keller.offset=-0.41 decibar;

2022-05-25T19:48:52.518Z,1653508132.518 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): Express linearApproximation CBIT.ampHoursUsed 5.0 ampere_hour;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): MassServo.loadAtStartup=0 bool;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=34.5 degree;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=34.4381 degree;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=34.2067 degree;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=34.13 degree;

2022-05-25T19:48:52.519Z,1653508132.519 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex5=34.4621 degree;

2022-05-25T19:48:52.520Z,1653508132.520 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2022-05-25T19:48:52.520Z,1653508132.520 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-120.84 degree;

2022-05-25T19:48:52.520Z,1653508132.520 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-120.069 degree;

2022-05-25T19:48:52.520Z,1653508132.520 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-119.331 degree;

2022-05-25T19:48:52.520Z,1653508132.520 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-119.331 degree;

2022-05-25T19:48:52.520Z,1653508132.520 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex5=-120.845 degree;

2022-05-25T19:48:52.521Z,1653508132.521 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter;

2022-05-25T19:48:52.521Z,1653508132.521 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2022-05-25T19:48:52.522Z,1653508132.522 [CBIT](INFO): Critical error at 20220525T194852
2022-05-25T19:48:52.929Z,1653508132.929 [MissionManager](IMPORTANT): Started mission Startup
2022-05-25T19:48:52.929Z,1653508132.929 [Startup] Running Loop=1
2022-05-25T19:48:52.929Z,1653508132.929 [Startup](DEBUG): Aggregate::initialize Startup
2022-05-25T19:48:52.930Z,1653508132.930 [Startup:A.GoToSurface] Running Loop=1
2022-05-25T19:48:52.930Z,1653508132.930 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-25T19:48:52.931Z,1653508132.931 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-25T19:48:52.931Z,1653508132.931 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-25T19:48:52.932Z,1653508132.932 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-25T19:48:52.933Z,1653508132.933 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-25T19:48:52.933Z,1653508132.933 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-25T19:48:52.934Z,1653508132.934 [Startup:StartupSatComms] Running Loop=1
2022-05-25T19:48:52.934Z,1653508132.934 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-05-25T19:48:52.935Z,1653508132.935 [Startup:StartupSatComms:A] Running Loop=1
2022-05-25T19:48:53.335Z,1653508133.335 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-05-25T19:49:53.144Z,1653508193.144 [Startup:StartupSatComms:A](INFO): Timed out from 2022-05-25T19:48:52.9Z
2022-05-25T19:49:53.144Z,1653508193.144 [Startup:StartupSatComms:A] Stopped
2022-05-25T19:49:53.144Z,1653508193.144 [Startup:StartupSatComms:B] Running Loop=1
2022-05-25T19:49:53.537Z,1653508193.537 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-05-25T19:49:59.235Z,1653508199.235 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-05-25T19:49:59.235Z,1653508199.235 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-25T19:49:59.253Z,1653508199.253 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-25T19:49:59.660Z,1653508199.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-25T19:49:59.660Z,1653508199.660 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-05-25T19:50:00.751Z,1653508200.751 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20220525T193157/Courier0004.lzma
2022-05-25T19:50:01.754Z,1653508201.754 [DataOverHttps](INFO): Moved sent file to Logs/20220525T193157/Courier0004.lzma.bak
2022-05-25T19:50:01.754Z,1653508201.754 [DataOverHttps](INFO): SBD MOMSN=16827589
2022-05-25T19:50:17.256Z,1653508217.256 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20220525T194403/Courier0000.lzma
2022-05-25T19:50:18.258Z,1653508218.258 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194403/Courier0000.lzma.bak
2022-05-25T19:50:18.258Z,1653508218.258 [DataOverHttps](INFO): SBD MOMSN=16827592
2022-05-25T19:50:33.940Z,1653508233.940 [DataOverHttps](INFO): Sending 316 bytes from file Logs/20220525T194529/Courier0000.lzma
2022-05-25T19:50:34.942Z,1653508234.942 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194529/Courier0000.lzma.bak
2022-05-25T19:50:34.942Z,1653508234.942 [DataOverHttps](INFO): SBD MOMSN=16827598
2022-05-25T19:50:49.296Z,1653508249.296 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 748
2022-05-25T19:50:49.298Z,1653508249.298 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2022-05-25T19:50:50.548Z,1653508250.548 [DataOverHttps](INFO): Sending 338 bytes from file Logs/20220525T194649/Courier0000.lzma
2022-05-25T19:50:51.550Z,1653508251.550 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194649/Courier0000.lzma.bak
2022-05-25T19:50:51.550Z,1653508251.550 [DataOverHttps](INFO): SBD MOMSN=16827604
2022-05-25T19:50:53.366Z,1653508253.366 [Startup:StartupSatComms:B](INFO): Timed out from 2022-05-25T19:49:53.1Z
2022-05-25T19:50:53.366Z,1653508253.366 [Startup:StartupSatComms:B] Stopped
2022-05-25T19:50:53.366Z,1653508253.366 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-05-25T19:50:53.366Z,1653508253.366 [Startup:StartupSatComms] Stopped
2022-05-25T19:50:53.366Z,1653508253.366 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-05-25T19:50:53.367Z,1653508253.367 [Startup](INFO): Completed Startup
2022-05-25T19:50:53.367Z,1653508253.367 [MissionManager](INFO): Startup is completed.
2022-05-25T19:50:53.367Z,1653508253.367 [MissionManager](INFO): Uninitializing Mission Startup
2022-05-25T19:50:53.367Z,1653508253.367 [Startup] Stopped
2022-05-25T19:50:53.367Z,1653508253.367 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-05-25T19:50:53.367Z,1653508253.367 [Startup:A.GoToSurface] Stopped
2022-05-25T19:50:53.367Z,1653508253.367 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-25T19:50:53.764Z,1653508253.764 [MissionManager](IMPORTANT): Started mission Default
2022-05-25T19:50:53.764Z,1653508253.764 [Default] Running Loop=1
2022-05-25T19:50:53.764Z,1653508253.764 [Default](DEBUG): Aggregate::initialize Default
2022-05-25T19:50:53.764Z,1653508253.764 [Default:B.GoToSurface] Running Loop=1
2022-05-25T19:50:53.764Z,1653508253.764 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-25T19:50:53.765Z,1653508253.765 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-25T19:50:53.765Z,1653508253.765 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-25T19:50:53.765Z,1653508253.765 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-25T19:50:53.765Z,1653508253.765 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-25T19:50:53.766Z,1653508253.766 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-25T19:50:53.766Z,1653508253.766 [Default:A.Wait] Running Loop=1
2022-05-25T19:50:53.766Z,1653508253.766 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-05-25T19:51:07.087Z,1653508267.087 [Default:A.Wait](INFO): Done Waiting.
2022-05-25T19:51:07.087Z,1653508267.087 [Default:A.Wait] Stopped
2022-05-25T19:51:07.087Z,1653508267.087 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-05-25T19:51:07.498Z,1653508267.498 [Default:CheckIn] Running Loop=1
2022-05-25T19:51:07.499Z,1653508267.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-05-25T19:51:07.499Z,1653508267.499 [Default:CheckIn:Read_GPS] Running Loop=1
2022-05-25T19:51:07.906Z,1653508267.906 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-05-25T19:51:30.240Z,1653508290.240 [DataOverHttps](INFO): Sending 589 bytes from file Logs/20220525T193157/Express0005.lzma
2022-05-25T19:51:31.242Z,1653508291.242 [DataOverHttps](INFO): Moved sent file to Logs/20220525T193157/Express0005.lzma.bak
2022-05-25T19:51:31.242Z,1653508291.242 [DataOverHttps](INFO): SBD MOMSN=16827611
2022-05-25T19:52:43.232Z,1653508363.232 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-05-25T19:53:00.195Z,1653508380.195 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-05-25T19:53:00.195Z,1653508380.195 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-25T19:53:00.205Z,1653508380.205 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-25T19:53:00.617Z,1653508380.617 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-25T19:53:00.617Z,1653508380.617 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-05-25T19:56:01.212Z,1653508561.212 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-05-25T19:56:01.212Z,1653508561.212 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-25T19:56:01.249Z,1653508561.249 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-25T19:56:01.650Z,1653508561.650 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-25T19:56:01.650Z,1653508561.650 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-05-25T19:56:07.710Z,1653508567.710 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-25T19:51:07.5Z
2022-05-25T19:56:07.710Z,1653508567.710 [Default:CheckIn:Read_GPS] Stopped
2022-05-25T19:56:07.710Z,1653508567.710 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-05-25T19:56:08.078Z,1653508568.078 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-05-25T19:56:14.675Z,1653508574.675 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220525T194649/Courier0004.lzma
2022-05-25T19:56:15.678Z,1653508575.678 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194649/Courier0004.lzma.bak
2022-05-25T19:56:15.678Z,1653508575.678 [DataOverHttps](INFO): SBD MOMSN=16827629
2022-05-25T19:56:32.644Z,1653508592.644 [DataOverHttps](INFO): Sending 379 bytes from file Logs/20220525T194403/Express0001.lzma
2022-05-25T19:56:33.646Z,1653508593.646 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194403/Express0001.lzma.bak
2022-05-25T19:56:33.646Z,1653508593.646 [DataOverHttps](INFO): SBD MOMSN=16827631
2022-05-25T19:56:49.288Z,1653508609.288 [DataOverHttps](INFO): Sending 379 bytes from file Logs/20220525T194529/Express0001.lzma
2022-05-25T19:56:50.290Z,1653508610.290 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194529/Express0001.lzma.bak
2022-05-25T19:56:50.290Z,1653508610.290 [DataOverHttps](INFO): SBD MOMSN=16827640
2022-05-25T19:57:05.193Z,1653508625.193 [DataOverHttps](INFO): Sending 1173 bytes from file Logs/20220525T194649/Express0001.lzma
2022-05-25T19:57:06.194Z,1653508626.194 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194649/Express0001.lzma.bak
2022-05-25T19:57:06.194Z,1653508626.194 [DataOverHttps](INFO): SBD MOMSN=16827649
2022-05-25T19:57:22.007Z,1653508642.007 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20220525T194649/Express0005.lzma
2022-05-25T19:57:23.010Z,1653508643.010 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194649/Express0005.lzma.bak
2022-05-25T19:57:23.010Z,1653508643.010 [DataOverHttps](INFO): SBD MOMSN=16827688
2022-05-25T19:57:25.254Z,1653508645.254 [Default:CheckIn:Read_Iridium] Stopped
2022-05-25T19:57:25.254Z,1653508645.254 [Default:CheckIn:C.Wait] Running Loop=1
2022-05-25T19:57:25.254Z,1653508645.254 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-05-25T19:57:40.578Z,1653508660.578 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-05-25T19:57:40.578Z,1653508660.578 [NAL9602] Data Fault, FailCount= 1
2022-05-25T19:57:40.578Z,1653508660.578 [NAL9602](ERROR): Data Fault
2022-05-25T19:57:40.624Z,1653508660.624 [CBIT](ERROR): Data Fault in component: NAL9602
2022-05-25T19:57:40.982Z,1653508660.982 [NAL9602](INFO): Powering down
2022-05-25T19:57:41.807Z,1653508661.807 [CBIT](INFO): Clearing failed state for component NAL9602
2022-05-25T19:57:41.807Z,1653508661.807 [NAL9602] No Fault, FailCount= 1
2022-05-25T19:58:11.278Z,1653508691.278 [NAL9602](INFO): Powering up NAL9602
2022-05-25T19:58:22.186Z,1653508702.186 [NAL9602](INFO): NAL9602 initialized
2022-05-25T19:59:02.234Z,1653508742.234 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-05-25T19:59:02.234Z,1653508742.234 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-25T19:59:02.252Z,1653508742.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-25T19:59:02.658Z,1653508742.658 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-25T19:59:02.658Z,1653508742.658 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-05-25T20:01:59.999Z,1653508919.999 [CBIT](INFO): Clearing failed state for component DropWeight
2022-05-25T20:01:59.999Z,1653508919.999 [DropWeight] No Fault, FailCount= 1
2022-05-25T20:02:03.203Z,1653508923.203 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-05-25T20:02:03.203Z,1653508923.203 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-25T20:02:03.214Z,1653508923.214 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-25T20:02:03.623Z,1653508923.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-25T20:02:03.623Z,1653508923.623 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-05-25T20:02:25.846Z,1653508945.846 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-05-25T20:02:25.846Z,1653508945.846 [Default:CheckIn:C.Wait] Stopped
2022-05-25T20:02:25.846Z,1653508945.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-05-25T20:02:25.846Z,1653508945.846 [Default:CheckIn:D] Running Loop=1
2022-05-25T20:02:26.240Z,1653508946.240 [Default:CheckIn:D] Stopped
2022-05-25T20:02:26.240Z,1653508946.240 [Default:CheckIn:E] Running Loop=1
2022-05-25T20:02:26.664Z,1653508946.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.541267 min 
2022-05-25T20:02:26.664Z,1653508946.664 [Default:CheckIn:E] Stopped
2022-05-25T20:02:26.665Z,1653508946.665 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-05-25T20:02:26.665Z,1653508946.665 [Default:CheckIn] Stopped
2022-05-25T20:02:26.665Z,1653508946.665 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-05-25T20:02:26.665Z,1653508946.665 [Default:CheckIn](INFO): Running loop #2
2022-05-25T20:02:26.665Z,1653508946.665 [Default:CheckIn] Running Loop=2
2022-05-25T20:02:26.665Z,1653508946.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-05-25T20:02:26.665Z,1653508946.665 [Default:CheckIn:Read_GPS] Running Loop=1
2022-05-25T20:03:34.914Z,1653509014.914 [DVL_micro](ERROR): Failed to parse:
:8,+01023,-00767,+00000,I
2022-05-25T20:05:04.204Z,1653509104.204 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-05-25T20:05:04.204Z,1653509104.204 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-25T20:05:04.214Z,1653509104.214 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-25T20:05:04.601Z,1653509104.601 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-25T20:05:04.601Z,1653509104.601 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-05-25T20:07:26.825Z,1653509246.825 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-25T20:02:26.7Z
2022-05-25T20:07:26.826Z,1653509246.826 [Default:CheckIn:Read_GPS] Stopped
2022-05-25T20:07:26.826Z,1653509246.826 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-05-25T20:07:36.879Z,1653509256.879 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220525T194649/Courier0007.lzma
2022-05-25T20:07:37.874Z,1653509257.874 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194649/Courier0007.lzma.bak
2022-05-25T20:07:37.874Z,1653509257.874 [DataOverHttps](INFO): SBD MOMSN=16827753
2022-05-25T20:08:03.516Z,1653509283.516 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20220525T194649/Express0008.lzma
2022-05-25T20:08:04.518Z,1653509284.518 [DataOverHttps](INFO): Moved sent file to Logs/20220525T194649/Express0008.lzma.bak
2022-05-25T20:08:04.518Z,1653509284.518 [DataOverHttps](INFO): SBD MOMSN=16827755
2022-05-25T20:08:05.322Z,1653509285.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-05-25T20:08:05.322Z,1653509285.322 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-25T20:08:05.372Z,1653509285.372 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-25T20:08:05.717Z,1653509285.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-25T20:08:05.717Z,1653509285.717 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-05-25T20:08:07.333Z,1653509287.333 [Default:CheckIn:Read_Iridium] Stopped
2022-05-25T20:08:07.333Z,1653509287.333 [Default:CheckIn:C.Wait] Running Loop=1
2022-05-25T20:08:07.333Z,1653509287.333 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-05-25T20:08:10.139Z,1653509290.139 [DVL_micro](ERROR): only read 3 of 4 data items
2022-05-25T20:08:10.139Z,1653509290.139 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2022-05-25T20:08:23.854Z,1653509303.854 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-05-25T20:08:23.854Z,1653509303.854 [NAL9602] Data Fault, FailCount= 2
2022-05-25T20:08:23.854Z,1653509303.854 [NAL9602](ERROR): Data Fault
2022-05-25T20:08:23.922Z,1653509303.922 [CBIT](ERROR): Data Fault in component: NAL9602
2022-05-25T20:08:24.270Z,1653509304.270 [NAL9602](INFO): Powering down
2022-05-25T20:08:25.150Z,1653509305.150 [CBIT](INFO): Clearing failed state for component NAL9602
2022-05-25T20:08:25.150Z,1653509305.150 [NAL9602] No Fault, FailCount= 2
2022-05-25T20:08:54.558Z,1653509334.558 [NAL9602](INFO): Powering up NAL9602
2022-05-25T20:09:05.467Z,1653509345.467 [NAL9602](INFO): NAL9602 initialized
2022-05-25T20:09:13.117Z,1653509353.117 [CommandExec](IMPORTANT): got command gfscan
2022-05-25T20:09:13.214Z,1653509353.214 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-25T20:09:24.118Z,1653509364.118 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006686
CHAN A1 (24V): 0.002390
CHAN A2 (12V): -0.004740
CHAN A3 (5V): -0.002319
CHAN B0 (3.3V): -0.001129
CHAN B1 (3.15aV): -0.000752
CHAN B2 (3.15bV): -0.000923
CHAN B3 (GND): -0.000059
OPEN: 0.004917
Full Scale: +/- 1 mA
2022-05-25T20:09:38.817Z,1653509378.817 [CommandExec](IMPORTANT): got command ! echo d > /dev/loadA7
2022-05-25T20:09:41.213Z,1653509381.213 [CommandExec](IMPORTANT): got command ! echo d > /dev/loadA6
2022-05-25T20:09:43.817Z,1653509383.817 [CommandExec](IMPORTANT): got command ! echo d > /dev/loadA5
2022-05-25T20:09:46.841Z,1653509386.841 [CommandExec](IMPORTANT): got command ! echo d > /dev/loadA8
2022-05-25T20:09:49.549Z,1653509389.549 [CommandExec](IMPORTANT): got command gfscan
2022-05-25T20:09:49.924Z,1653509389.924 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-25T20:10:00.832Z,1653509400.832 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007036
CHAN A1 (24V): 0.002237
CHAN A2 (12V): -0.004323
CHAN A3 (5V): -0.000021
CHAN B0 (3.3V): 0.000167
CHAN B1 (3.15aV): -0.001006
CHAN B2 (3.15bV): -0.000949
CHAN B3 (GND): -0.000409
OPEN: 0.005585
Full Scale: +/- 1 mA
2022-05-25T20:10:08.285Z,1653509408.285 [CommandExec](IMPORTANT): got command quit
2022-05-25T20:10:09.288Z,1653509409.288 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:09.288Z,1653509409.288 [CommandExec](INFO): Uninitializing the command executive.
2022-05-25T20:10:09.288Z,1653509409.288 [CommandExec](INFO): Uninitializing the command scheduler.
2022-05-25T20:10:09.288Z,1653509409.288 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:09.324Z,1653509409.324 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-05-25T20:10:09.325Z,1653509409.325 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-05-25T20:10:09.325Z,1653509409.325 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:09.326Z,1653509409.326 [NavChartDb](INFO): Join timeout helper Thread ID is 1569
2022-05-25T20:10:09.416Z,1653509409.416 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:09.416Z,1653509409.416 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:09.428Z,1653509409.428 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-05-25T20:10:09.428Z,1653509409.428 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:09.429Z,1653509409.429 [Radio_Surface](INFO): Join timeout helper Thread ID is 1570
2022-05-25T20:10:09.796Z,1653509409.796 [Radio_Surface](INFO): Powering down
2022-05-25T20:10:09.797Z,1653509409.797 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:09.797Z,1653509409.797 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:09.800Z,1653509409.800 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-05-25T20:10:09.800Z,1653509409.800 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:09.800Z,1653509409.800 [Onboard](INFO): Join timeout helper Thread ID is 1571
2022-05-25T20:10:10.144Z,1653509410.144 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.144Z,1653509410.144 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.164Z,1653509410.164 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-05-25T20:10:10.164Z,1653509410.164 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.164Z,1653509410.164 [DataOverHttps](INFO): Join timeout helper Thread ID is 1572
2022-05-25T20:10:10.344Z,1653509410.344 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.344Z,1653509410.344 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.352Z,1653509410.352 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-05-25T20:10:10.352Z,1653509410.352 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.352Z,1653509410.352 [BackseatComponent](INFO): Join timeout helper Thread ID is 1573
2022-05-25T20:10:10.528Z,1653509410.528 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.528Z,1653509410.528 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.549Z,1653509410.549 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-05-25T20:10:10.549Z,1653509410.549 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.549Z,1653509410.549 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1574
2022-05-25T20:10:10.572Z,1653509410.572 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.572Z,1653509410.572 [WetLabsBB2FL](INFO): Powering down
2022-05-25T20:10:10.573Z,1653509410.573 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.574Z,1653509410.574 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-05-25T20:10:10.575Z,1653509410.575 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.575Z,1653509410.575 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1575
2022-05-25T20:10:10.692Z,1653509410.692 [CTD_Seabird](INFO): Powering down
2022-05-25T20:10:10.704Z,1653509410.704 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.704Z,1653509410.704 [CTD_Seabird](INFO): Powering down
2022-05-25T20:10:10.716Z,1653509410.716 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.717Z,1653509410.717 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-05-25T20:10:10.717Z,1653509410.717 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.718Z,1653509410.718 [logger](INFO): Join timeout helper Thread ID is 1576
2022-05-25T20:10:10.744Z,1653509410.744 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.744Z,1653509410.744 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.756Z,1653509410.756 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-05-25T20:10:10.757Z,1653509410.757 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.757Z,1653509410.757 [CommandLine](INFO): Join timeout helper Thread ID is 1577
2022-05-25T20:10:10.805Z,1653509410.805 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.806Z,1653509410.806 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.816Z,1653509410.816 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-05-25T20:10:10.817Z,1653509410.817 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.817Z,1653509410.817 [CommandExec](INFO): Join timeout helper Thread ID is 1578
2022-05-25T20:10:10.818Z,1653509410.818 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-05-25T20:10:10.818Z,1653509410.818 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:10.819Z,1653509410.819 [controlThread](INFO): Join timeout helper Thread ID is 1579
2022-05-25T20:10:10.896Z,1653509410.896 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-25T20:10:10.896Z,1653509410.896 [controlThread](DEBUG): Uninitializing ControlThread
2022-05-25T20:10:10.898Z,1653509410.898 [AHRS_M2](INFO): Powering down
2022-05-25T20:10:11.040Z,1653509411.040 [DVL_micro](INFO): Powering down
2022-05-25T20:10:11.041Z,1653509411.041 [NAL9602](INFO): Powering down
2022-05-25T20:10:11.042Z,1653509411.042 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-05-25T20:10:11.043Z,1653509411.043 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-05-25T20:10:11.044Z,1653509411.044 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-05-25T20:10:11.045Z,1653509411.045 [MissionManager](INFO): Uninitializing Mission Default
2022-05-25T20:10:11.045Z,1653509411.045 [Default] Stopped
2022-05-25T20:10:11.045Z,1653509411.045 [Default](DEBUG): Aggregate::uninitialize Default
2022-05-25T20:10:11.045Z,1653509411.045 [Default:B.GoToSurface] Stopped
2022-05-25T20:10:11.045Z,1653509411.045 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-25T20:10:11.045Z,1653509411.045 [Default:CheckIn] Stopped
2022-05-25T20:10:11.045Z,1653509411.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-05-25T20:10:11.045Z,1653509411.045 [Default:CheckIn:C.Wait] Stopped
2022-05-25T20:10:11.045Z,1653509411.045 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-05-25T20:10:11.048Z,1653509411.048 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-05-25T20:10:11.049Z,1653509411.049 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-05-25T20:10:11.049Z,1653509411.049 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-05-25T20:10:11.049Z,1653509411.049 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-05-25T20:10:11.049Z,1653509411.049 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-05-25T20:10:11.050Z,1653509411.050 [BuoyancyServo](INFO): Powering down
2022-05-25T20:10:11.064Z,1653509411.064 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-25T20:10:11.064Z,1653509411.064 [ElevatorServo](INFO): Powering down
2022-05-25T20:10:11.065Z,1653509411.065 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-25T20:10:11.065Z,1653509411.065 [RudderServo](INFO): Powering down
2022-05-25T20:10:11.066Z,1653509411.066 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-05-25T20:10:11.066Z,1653509411.066 [ThrusterHE](INFO): Powering down
2022-05-25T20:10:11.067Z,1653509411.067 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-05-25T20:10:11.067Z,1653509411.067 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-05-25T20:10:11.067Z,1653509411.067 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-05-25T20:10:11.068Z,1653509411.068 [CBIT](DEBUG): Powering off loads.
2022-05-25T20:10:11.080Z,1653509411.080 [CBIT](DEBUG): Disabling WDT.
2022-05-25T20:10:11.092Z,1653509411.092 [CBIT](DEBUG): Opening all GF detection circuits.
2022-05-25T20:10:11.093Z,1653509411.093 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.126Z,1653509411.126 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.132Z,1653509411.132 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.162Z,1653509411.162 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.164Z,1653509411.164 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.171Z,1653509411.171 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.178Z,1653509411.178 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.228Z,1653509411.228 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-25T20:10:11.293Z,1653509411.293 [logger ThreadHandler](INFO): Thread cancelled.