2023-08-01T16:31:12.526Z,1690907472.526 [Supervisor](DEBUG): Initializing supervisor.
2023-08-01T16:31:12.531Z,1690907472.531 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-01T16:31:12.531Z,1690907472.531 [SyncHandler](INFO): Protected caller Thread ID is 1343
2023-08-01T16:31:12.532Z,1690907472.532 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-01T16:31:12.533Z,1690907472.533 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-01T16:31:12.533Z,1690907472.533 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1344
2023-08-01T16:31:12.537Z,1690907472.537 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-01T16:31:12.556Z,1690907472.556 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-01T16:31:12.557Z,1690907472.557 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-01T16:31:12.557Z,1690907472.557 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1345
2023-08-01T16:31:12.562Z,1690907472.562 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-01T16:31:12.563Z,1690907472.563 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-01T16:31:12.563Z,1690907472.563 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1346
2023-08-01T16:31:12.565Z,1690907472.565 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-01T16:31:12.566Z,1690907472.566 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-01T16:31:12.566Z,1690907472.566 [logger ThreadHandler](INFO): Protected caller Thread ID is 1347
2023-08-01T16:31:12.570Z,1690907472.570 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-01T16:31:12.571Z,1690907472.571 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-01T16:31:12.572Z,1690907472.572 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-01T16:31:12.724Z,1690907472.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-01T16:31:12.725Z,1690907472.725 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-01T16:31:12.963Z,1690907472.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-01T16:31:12.963Z,1690907472.963 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-01T16:31:13.253Z,1690907473.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-01T16:31:13.253Z,1690907473.253 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-01T16:31:13.367Z,1690907473.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-01T16:31:13.367Z,1690907473.367 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-01T16:31:14.489Z,1690907474.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-01T16:31:14.489Z,1690907474.489 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-01T16:31:15.013Z,1690907475.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-01T16:31:15.014Z,1690907475.014 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-01T16:31:15.542Z,1690907475.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-01T16:31:15.543Z,1690907475.543 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-01T16:31:15.983Z,1690907475.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-01T16:31:15.984Z,1690907475.984 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-01T16:31:16.359Z,1690907476.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-01T16:31:16.360Z,1690907476.360 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-01T16:31:16.761Z,1690907476.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-01T16:31:16.761Z,1690907476.761 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-01T16:31:16.998Z,1690907476.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-01T16:31:16.999Z,1690907476.999 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-01T16:31:17.145Z,1690907477.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-01T16:31:17.146Z,1690907477.146 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-01T16:31:17.229Z,1690907477.229 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-01T16:31:17.311Z,1690907477.311 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-01T16:31:17.516Z,1690907477.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-01T16:31:17.517Z,1690907477.517 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-01T16:31:17.761Z,1690907477.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-01T16:31:17.763Z,1690907477.763 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-08-01T16:31:17.763Z,1690907477.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-08-01T16:31:17.851Z,1690907477.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-08-01T16:31:18.125Z,1690907478.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-01T16:31:18.125Z,1690907478.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-08-01T16:31:18.237Z,1690907478.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-08-01T16:31:18.409Z,1690907478.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-08-01T16:31:18.515Z,1690907478.515 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-08-01T16:31:18.634Z,1690907478.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-08-01T16:31:18.751Z,1690907478.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-08-01T16:31:18.861Z,1690907478.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-08-01T16:31:19.035Z,1690907479.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2023-08-01T16:31:19.136Z,1690907479.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-08-01T16:31:19.238Z,1690907479.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-08-01T16:31:19.346Z,1690907479.346 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2023-08-01T16:31:19.346Z,1690907479.346 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-08-01T16:31:19.364Z,1690907479.364 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-01T16:31:19.527Z,1690907479.527 [InternalEnvSim] Loaded
2023-08-01T16:31:19.527Z,1690907479.527 [ComponentRegistry](DEBUG): SyncComponent "InternalEnvSim" handled in the control thread.
2023-08-01T16:31:19.528Z,1690907479.528 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-01T16:31:19.529Z,1690907479.529 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-01T16:31:19.879Z,1690907479.879 [BuoyancyServo] Loaded
2023-08-01T16:31:19.880Z,1690907479.880 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-01T16:31:19.901Z,1690907479.901 [ElevatorServo] Loaded
2023-08-01T16:31:19.902Z,1690907479.902 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-01T16:31:19.951Z,1690907479.951 [MassServo] Loaded
2023-08-01T16:31:19.962Z,1690907479.962 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-01T16:31:20.011Z,1690907480.011 [RudderServo] Loaded
2023-08-01T16:31:20.011Z,1690907480.011 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-01T16:31:20.082Z,1690907480.082 [ThrusterHE] Loaded
2023-08-01T16:31:20.082Z,1690907480.082 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-01T16:31:20.082Z,1690907480.082 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-01T16:31:20.083Z,1690907480.083 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-01T16:31:20.317Z,1690907480.317 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-01T16:31:20.341Z,1690907480.341 [SBIT] Loaded
2023-08-01T16:31:20.341Z,1690907480.341 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-01T16:31:20.364Z,1690907480.364 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-01T16:31:20.405Z,1690907480.405 [IBIT] Loaded
2023-08-01T16:31:20.406Z,1690907480.406 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-01T16:31:20.419Z,1690907480.419 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-01T16:31:20.722Z,1690907480.722 [CBIT] Loaded
2023-08-01T16:31:20.722Z,1690907480.722 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-01T16:31:20.723Z,1690907480.723 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-01T16:31:20.723Z,1690907480.723 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-01T16:31:20.899Z,1690907480.899 [CTD_Seabird] Loaded
2023-08-01T16:31:20.899Z,1690907480.899 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-01T16:31:20.901Z,1690907480.901 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406674E0
2023-08-01T16:31:20.901Z,1690907480.901 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1432
2023-08-01T16:31:20.933Z,1690907480.933 [ESPComponent] Loaded
2023-08-01T16:31:20.934Z,1690907480.934 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-08-01T16:31:20.954Z,1690907480.954 [PAR_Licor] Loaded
2023-08-01T16:31:20.954Z,1690907480.954 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-01T16:31:20.995Z,1690907480.995 [WetLabsBB2FL] Loaded
2023-08-01T16:31:20.995Z,1690907480.995 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-01T16:31:20.996Z,1690907480.996 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406974E0
2023-08-01T16:31:20.996Z,1690907480.996 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1433
2023-08-01T16:31:21.027Z,1690907481.027 [WetLabsSeaOWL_UV_A] Loaded
2023-08-01T16:31:21.027Z,1690907481.027 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2023-08-01T16:31:21.028Z,1690907481.028 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406C74E0
2023-08-01T16:31:21.029Z,1690907481.029 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1434
2023-08-01T16:31:21.029Z,1690907481.029 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-01T16:31:21.030Z,1690907481.030 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-01T16:31:21.088Z,1690907481.088 [DepthRateCalculator] Loaded
2023-08-01T16:31:21.088Z,1690907481.088 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-01T16:31:21.093Z,1690907481.093 [PitchRateCalculator] Loaded
2023-08-01T16:31:21.094Z,1690907481.094 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-01T16:31:21.105Z,1690907481.105 [SpeedCalculator] Loaded
2023-08-01T16:31:21.105Z,1690907481.105 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-01T16:31:21.110Z,1690907481.110 [YawRateCalculator] Loaded
2023-08-01T16:31:21.110Z,1690907481.110 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-01T16:31:21.130Z,1690907481.130 [ElevatorOffsetCalculator] Loaded
2023-08-01T16:31:21.130Z,1690907481.130 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-01T16:31:21.147Z,1690907481.147 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-08-01T16:31:21.147Z,1690907481.147 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-08-01T16:31:21.148Z,1690907481.148 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-01T16:31:21.148Z,1690907481.148 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-01T16:31:21.259Z,1690907481.259 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-01T16:31:21.260Z,1690907481.260 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-01T16:31:21.293Z,1690907481.293 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-01T16:31:21.294Z,1690907481.294 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-01T16:31:21.402Z,1690907481.402 [DeadReckonUsingSpeedCalculator] Loaded
2023-08-01T16:31:21.403Z,1690907481.403 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-08-01T16:31:21.417Z,1690907481.417 [NavChart] Loaded
2023-08-01T16:31:21.417Z,1690907481.417 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-01T16:31:21.423Z,1690907481.423 [UniversalFixResidualReporter] Loaded
2023-08-01T16:31:21.423Z,1690907481.423 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-01T16:31:21.424Z,1690907481.424 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-01T16:31:21.424Z,1690907481.424 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-01T16:31:21.870Z,1690907481.870 [AHRS_M2] Loaded
2023-08-01T16:31:21.871Z,1690907481.871 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-01T16:31:21.906Z,1690907481.906 [BackseatComponent] Loaded
2023-08-01T16:31:21.906Z,1690907481.906 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-01T16:31:21.907Z,1690907481.907 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A5D4E0
2023-08-01T16:31:21.907Z,1690907481.907 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1435
2023-08-01T16:31:21.910Z,1690907481.910 [LcmUniversalReporter] Loaded
2023-08-01T16:31:21.910Z,1690907481.910 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-01T16:31:22.660Z,1690907482.660 [BPC1] Loaded
2023-08-01T16:31:22.660Z,1690907482.660 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-01T16:31:22.734Z,1690907482.734 [DataOverHttps] Loaded
2023-08-01T16:31:22.734Z,1690907482.734 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-01T16:31:22.735Z,1690907482.735 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A8D4E0
2023-08-01T16:31:22.735Z,1690907482.735 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1436
2023-08-01T16:31:22.756Z,1690907482.756 [Depth_Keller] Loaded
2023-08-01T16:31:22.757Z,1690907482.757 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-01T16:31:22.762Z,1690907482.762 [DropWeight] Loaded
2023-08-01T16:31:22.762Z,1690907482.762 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-01T16:31:22.802Z,1690907482.802 [DVL_micro] Loaded
2023-08-01T16:31:22.802Z,1690907482.802 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-08-01T16:31:22.863Z,1690907482.863 [NAL9602] Loaded
2023-08-01T16:31:22.863Z,1690907482.863 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-01T16:31:22.890Z,1690907482.890 [Onboard] Loaded
2023-08-01T16:31:22.890Z,1690907482.890 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-01T16:31:22.891Z,1690907482.891 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40ABD4E0
2023-08-01T16:31:22.892Z,1690907482.892 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1437
2023-08-01T16:31:22.904Z,1690907482.904 [Power24vConverter] Loaded
2023-08-01T16:31:22.905Z,1690907482.905 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-01T16:31:22.918Z,1690907482.918 [Radio_Surface] Loaded
2023-08-01T16:31:22.919Z,1690907482.919 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-01T16:31:22.920Z,1690907482.920 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AED4E0
2023-08-01T16:31:22.920Z,1690907482.920 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1438
2023-08-01T16:31:22.934Z,1690907482.934 [Sonardyne_Nano] Loaded
2023-08-01T16:31:22.934Z,1690907482.934 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-08-01T16:31:22.935Z,1690907482.935 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-01T16:31:22.935Z,1690907482.935 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-01T16:31:22.993Z,1690907482.993 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-01T16:31:22.994Z,1690907482.994 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-01T16:31:23.045Z,1690907483.045 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-01T16:31:23.096Z,1690907483.096 [VerticalControl] Loaded
2023-08-01T16:31:23.097Z,1690907483.097 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-01T16:31:23.099Z,1690907483.099 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-01T16:31:23.138Z,1690907483.138 [HorizontalControl] Loaded
2023-08-01T16:31:23.139Z,1690907483.139 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-01T16:31:23.141Z,1690907483.141 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-01T16:31:23.143Z,1690907483.143 [SpeedControl] Loaded
2023-08-01T16:31:23.143Z,1690907483.143 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-01T16:31:23.146Z,1690907483.146 [LoopControl](DEBUG): Construct LoopControl.
2023-08-01T16:31:23.146Z,1690907483.146 [LoopControl] Loaded
2023-08-01T16:31:23.147Z,1690907483.147 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-01T16:31:23.147Z,1690907483.147 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-01T16:31:23.148Z,1690907483.148 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-01T16:31:23.158Z,1690907483.158 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-01T16:31:23.159Z,1690907483.159 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-01T16:31:23.480Z,1690907483.480 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-01T16:31:23.487Z,1690907483.487 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-01T16:31:23.490Z,1690907483.490 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-01T16:31:23.501Z,1690907483.501 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-01T16:31:23.503Z,1690907483.503 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF94E0
2023-08-01T16:31:23.503Z,1690907483.503 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1439
2023-08-01T16:31:23.508Z,1690907483.508 [Supervisor](INFO): Main Thread ID is 825
2023-08-01T16:31:23.508Z,1690907483.508 [Supervisor](DEBUG): Running supervisor.
2023-08-01T16:31:23.508Z,1690907483.508 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1440
2023-08-01T16:31:23.509Z,1690907483.509 [CommandExec](INFO): Initializing the command executive.
2023-08-01T16:31:23.510Z,1690907483.510 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1441
2023-08-01T16:31:23.514Z,1690907483.514 [controlThread ThreadHandler](INFO): Handler Thread ID is 1442
2023-08-01T16:31:23.514Z,1690907483.514 [controlThread](DEBUG): Initializing ControlThread
2023-08-01T16:31:23.515Z,1690907483.515 [InternalEnvSim](DEBUG):  InternaEnvlSim initializing...
2023-08-01T16:31:23.518Z,1690907483.518 [logger ThreadHandler](INFO): Handler Thread ID is 1443
2023-08-01T16:31:23.559Z,1690907483.559 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1444
2023-08-01T16:31:23.560Z,1690907483.560 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-01T16:31:23.577Z,1690907483.577 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1446
2023-08-01T16:31:23.584Z,1690907483.584 [WetLabsBB2FL](INFO): Powering up
2023-08-01T16:31:23.585Z,1690907483.585 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1448
2023-08-01T16:31:23.608Z,1690907483.608 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1449
2023-08-01T16:31:23.628Z,1690907483.628 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1450
2023-08-01T16:31:23.629Z,1690907483.629 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-01T16:31:23.644Z,1690907483.644 [Onboard ThreadHandler](INFO): Handler Thread ID is 1451
2023-08-01T16:31:23.684Z,1690907483.684 [SBIT](INFO): Initialize SBIT Component.
2023-08-01T16:31:23.685Z,1690907483.685 [SBIT](IMPORTANT): git: 2023-07-31-1-gc2e44910c
2023-08-01T16:31:23.685Z,1690907483.685 [SBIT](INFO): git hash: c2e44910c6e56a8023a8b1a81178d1dbbd33b4c5
2023-08-01T16:31:23.685Z,1690907483.685 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-01T16:31:23.687Z,1690907483.687 [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
2023-08-01T16:31:23.688Z,1690907483.688 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1452
2023-08-01T16:31:23.690Z,1690907483.690 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1453
2023-08-01T16:31:23.691Z,1690907483.691 [SBIT](INFO): Beginning SBIT in 54.000000 seconds.
2023-08-01T16:31:23.695Z,1690907483.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-08-01T16:31:23.695Z,1690907483.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-08-01T16:31:23.695Z,1690907483.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-08-01T16:31:23.695Z,1690907483.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-08-01T16:31:23.695Z,1690907483.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-08-01T16:31:23.696Z,1690907483.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-08-01T16:31:23.696Z,1690907483.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-08-01T16:31:23.696Z,1690907483.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-08-01T16:31:23.696Z,1690907483.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-08-01T16:31:23.696Z,1690907483.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-08-01T16:31:23.697Z,1690907483.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-08-01T16:31:23.697Z,1690907483.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-08-01T16:31:23.697Z,1690907483.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-08-01T16:31:23.697Z,1690907483.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-08-01T16:31:23.697Z,1690907483.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-08-01T16:31:23.697Z,1690907483.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-08-01T16:31:23.698Z,1690907483.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-08-01T16:31:23.698Z,1690907483.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-08-01T16:31:23.698Z,1690907483.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-08-01T16:31:23.698Z,1690907483.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-08-01T16:31:23.698Z,1690907483.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-08-01T16:31:23.698Z,1690907483.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-08-01T16:31:23.716Z,1690907483.716 [IBIT](INFO): Initialize IBIT Component.
2023-08-01T16:31:23.717Z,1690907483.717 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-01T16:31:23.728Z,1690907483.728 [CBIT](DEBUG): Initialized mux pins.
2023-08-01T16:31:23.728Z,1690907483.728 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-01T16:31:23.752Z,1690907483.752 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-01T16:31:23.752Z,1690907483.752 [CBIT](DEBUG): Initializing heartbeat.
2023-08-01T16:31:23.824Z,1690907483.824 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-01T16:31:23.824Z,1690907483.824 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-01T16:31:23.864Z,1690907483.864 [CBIT](DEBUG): Backplane powered.
2023-08-01T16:31:23.868Z,1690907483.868 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-01T16:31:23.869Z,1690907483.869 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-01T16:31:23.869Z,1690907483.869 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-01T16:31:23.869Z,1690907483.869 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-01T16:31:23.869Z,1690907483.869 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-01T16:31:23.870Z,1690907483.870 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-08-01T16:31:23.871Z,1690907483.871 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-01T16:31:23.872Z,1690907483.872 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-01T16:31:23.872Z,1690907483.872 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-01T16:31:23.882Z,1690907483.882 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-01T16:31:23.884Z,1690907483.884 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-01T16:31:23.885Z,1690907483.885 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-01T16:31:23.885Z,1690907483.885 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-01T16:31:23.886Z,1690907483.886 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-01T16:31:23.933Z,1690907483.933 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-01T16:31:23.964Z,1690907483.964 [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>


2023-08-01T16:31:23.965Z,1690907483.965 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-01T16:31:24.072Z,1690907484.072 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-01T16:31:24.074Z,1690907484.074 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-01T16:31:24.076Z,1690907484.076 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-01T16:31:24.092Z,1690907484.092 [Radio_Surface](INFO): Powering up
2023-08-01T16:31:24.128Z,1690907484.128 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-01T16:31:24.131Z,1690907484.131 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-01T16:31:24.138Z,1690907484.138 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-01T16:31:24.166Z,1690907484.166 [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="P4H">
                <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>


2023-08-01T16:31:24.171Z,1690907484.171 [controlThread](DEBUG): Component order: CycleStarter,InternalEnvSim,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-08-01T16:31:24.217Z,1690907484.217 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-01T16:31:24.281Z,1690907484.281 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.894409 decibar, 0.000000 m
2023-08-01T16:31:24.285Z,1690907484.285 [Power24vConverter](INFO): Powering up.
2023-08-01T16:31:24.286Z,1690907484.286 [Sonardyne_Nano](INFO): Initializing.
2023-08-01T16:31:24.312Z,1690907484.312 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-08-01T16:31:24.339Z,1690907484.339 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:31:24.348Z,1690907484.348 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-01T16:31:24.349Z,1690907484.349 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:31:24.356Z,1690907484.356 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-01T16:31:24.357Z,1690907484.357 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:31:24.364Z,1690907484.364 [MassServo](DEBUG): Initializing MassServo.
2023-08-01T16:31:24.365Z,1690907484.365 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:31:24.372Z,1690907484.372 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-01T16:31:24.373Z,1690907484.373 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-01T16:31:24.380Z,1690907484.380 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-01T16:31:24.696Z,1690907484.696 [DVL_micro](INFO): Powering down
2023-08-01T16:31:24.698Z,1690907484.698 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:31:24.990Z,1690907484.990 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:31:25.394Z,1690907485.394 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:31:25.688Z,1690907485.688 [WetLabsBB2FL](INFO): Powering down
2023-08-01T16:31:25.798Z,1690907485.798 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:31:26.201Z,1690907486.201 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:31:26.627Z,1690907486.627 [Sonardyne_Nano](INFO): Received response:>+++?

2023-08-01T16:31:26.627Z,1690907486.627 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-08-01T16:31:26.641Z,1690907486.641 [Sonardyne_Nano](INFO): Command indicated, sending CKHW
2023-08-01T16:31:27.010Z,1690907487.010 [Sonardyne_Nano](INFO): Command indicated, sending CKHW
2023-08-01T16:31:27.487Z,1690907487.487 [Sonardyne_Nano](INFO): Received response:>CKHW:PASS

2023-08-01T16:31:27.488Z,1690907487.488 [Sonardyne_Nano](INFO): Received self-test result
2023-08-01T16:31:27.488Z,1690907487.488 [Sonardyne_Nano](INFO): Command indicated, sending FS
2023-08-01T16:31:27.845Z,1690907487.845 [Sonardyne_Nano](INFO): Received response:>FS:2213,U006D25,FL0010,FV5.11.00.07,PV2.03,TDR;MF;OMNI;184;175;169,PR5000;SG;AC0.700,T-40;110;AC2.00,INC-90.00;+90.00;AC1.00

2023-08-01T16:31:27.845Z,1690907487.845 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2023-08-01T16:31:27.845Z,1690907487.845 [Sonardyne_Nano](INFO): Read UUID: %x27941
2023-08-01T16:31:27.845Z,1690907487.845 [Sonardyne_Nano](INFO): Command indicated, sending VS
2023-08-01T16:31:27.852Z,1690907487.852 [Sonardyne_Nano](INFO): Writing last charge 0 2023-08-01T16:31:27.845Z
2023-08-01T16:31:28.260Z,1690907488.260 [Sonardyne_Nano](INFO): Received response:>VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-12.0;CAP3.4/96;T25.6

2023-08-01T16:31:28.260Z,1690907488.260 [Sonardyne_Nano](INFO): Parsing volatile status
2023-08-01T16:31:28.260Z,1690907488.260 [Sonardyne_Nano](INFO): Parsing charge percentage, response: >VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-12.0;CAP3.4/96;T25.6

2023-08-01T16:31:28.260Z,1690907488.260 [Sonardyne_Nano](INFO): Found fwd slash
2023-08-01T16:31:28.261Z,1690907488.261 [Sonardyne_Nano](INFO): Charge percentage: 96
2023-08-01T16:31:28.261Z,1690907488.261 [Sonardyne_Nano](INFO): Writing last charge 96 2023-08-01T16:31:27.845Z
2023-08-01T16:31:30.069Z,1690907490.069 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-01T16:31:52.882Z,1690907512.882 [NAL9602](INFO): Powering up NAL9602
2023-08-01T16:32:03.778Z,1690907523.778 [NAL9602](INFO): NAL9602 initialized
2023-08-01T16:32:18.336Z,1690907538.336 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-01T16:32:18.340Z,1690907538.340 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-01T16:32:29.303Z,1690907549.303 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006135
CHAN A1 (24V): 0.002381
CHAN A2 (12V): -0.006231
CHAN A3 (5V): -0.002981
CHAN B0 (3.3V): -0.001567
CHAN B1 (3.15aV): -0.001927
CHAN B2 (3.15bV): -0.000731
CHAN B3 (GND): -0.000809
OPEN: 0.000862
Full Scale: +/- 1 mA
2023-08-01T16:33:12.479Z,1690907592.479 [SBIT](IMPORTANT): SBIT PASSED
2023-08-01T16:33:12.479Z,1690907592.479 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-08-01T16:33:12.480Z,1690907592.480 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup=0 bool;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup=1 bool;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): Depth_Keller.offset=-0.35 decibar;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2023-08-01T16:33:12.481Z,1690907592.481 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 1.000000 kilogram_per_cubic_meter;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234.769539 cubic_centimeter;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): VerticalControl.massDefault=8.539099 millimeter;

2023-08-01T16:33:12.482Z,1690907592.482 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-08-01T16:33:12.483Z,1690907592.483 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-08-01T16:33:12.483Z,1690907592.483 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=0 bool;

2023-08-01T16:33:12.890Z,1690907592.890 [MissionManager](IMPORTANT): Started mission Startup
2023-08-01T16:33:12.890Z,1690907592.890 [Startup] Running Loop=1
2023-08-01T16:33:12.890Z,1690907592.890 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-01T16:33:12.890Z,1690907592.890 [Startup:A.GoToSurface] Running Loop=1
2023-08-01T16:33:12.890Z,1690907592.890 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-01T16:33:12.891Z,1690907592.891 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-01T16:33:12.891Z,1690907592.891 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-01T16:33:12.892Z,1690907592.892 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-01T16:33:12.892Z,1690907592.892 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-01T16:33:12.893Z,1690907592.893 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-01T16:33:12.894Z,1690907592.894 [Startup:StartupSatComms] Running Loop=1
2023-08-01T16:33:12.894Z,1690907592.894 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-01T16:33:12.894Z,1690907592.894 [Startup:StartupSatComms:A] Running Loop=1
2023-08-01T16:33:13.306Z,1690907593.306 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-01T16:33:43.165Z,1690907623.165 [NAL9602](INFO): SBD MO Status=2, MOMSN=7066, MT Status=2, MTMSN=0
2023-08-01T16:33:43.165Z,1690907623.165 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:34:13.072Z,1690907653.072 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-01T16:33:12.9Z
2023-08-01T16:34:13.072Z,1690907653.072 [Startup:StartupSatComms:A] Stopped
2023-08-01T16:34:13.072Z,1690907653.072 [Startup:StartupSatComms:B] Running Loop=1
2023-08-01T16:34:13.492Z,1690907653.492 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-01T16:34:23.986Z,1690907663.986 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-08-01T16:34:23.986Z,1690907663.986 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-01T16:34:23.997Z,1690907663.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-01T16:34:24.390Z,1690907664.390 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-01T16:34:24.390Z,1690907664.390 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-08-01T16:34:24.781Z,1690907664.781 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-01T16:35:01.846Z,1690907701.846 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004514
2023-08-01T16:35:13.124Z,1690907713.124 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230801T161649/Courier0010.lzma
2023-08-01T16:35:13.445Z,1690907713.445 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-01T16:34:13.1Z
2023-08-01T16:35:13.445Z,1690907713.445 [Startup:StartupSatComms:B] Stopped
2023-08-01T16:35:13.445Z,1690907713.445 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-01T16:35:13.445Z,1690907713.445 [Startup:StartupSatComms] Stopped
2023-08-01T16:35:13.445Z,1690907713.445 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-01T16:35:13.446Z,1690907713.446 [Startup](INFO): Completed Startup
2023-08-01T16:35:13.446Z,1690907713.446 [MissionManager](INFO): Startup is completed.
2023-08-01T16:35:13.446Z,1690907713.446 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-01T16:35:13.446Z,1690907713.446 [Startup] Stopped
2023-08-01T16:35:13.446Z,1690907713.446 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-01T16:35:13.446Z,1690907713.446 [Startup:A.GoToSurface] Stopped
2023-08-01T16:35:13.447Z,1690907713.447 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-01T16:35:13.899Z,1690907713.899 [MissionManager](IMPORTANT): Started mission Default
2023-08-01T16:35:13.899Z,1690907713.899 [Default] Running Loop=1
2023-08-01T16:35:13.923Z,1690907713.923 [Default](DEBUG): Aggregate::initialize Default
2023-08-01T16:35:13.923Z,1690907713.923 [Default:B.GoToSurface] Running Loop=1
2023-08-01T16:35:13.923Z,1690907713.923 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-01T16:35:13.923Z,1690907713.923 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-01T16:35:13.924Z,1690907713.924 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-01T16:35:13.924Z,1690907713.924 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-01T16:35:13.925Z,1690907713.925 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-01T16:35:13.925Z,1690907713.925 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-01T16:35:13.925Z,1690907713.925 [Default:A.Wait] Running Loop=1
2023-08-01T16:35:13.925Z,1690907713.925 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:35:14.126Z,1690907714.126 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Courier0010.lzma.bak
2023-08-01T16:35:14.126Z,1690907714.126 [DataOverHttps](INFO): SBD MOMSN=18505148
2023-08-01T16:35:14.300Z,1690907714.300 [DVL_micro](INFO): Powering down
2023-08-01T16:35:27.170Z,1690907727.170 [Default:A.Wait](INFO): Done Waiting.
2023-08-01T16:35:27.171Z,1690907727.171 [Default:A.Wait] Stopped
2023-08-01T16:35:27.171Z,1690907727.171 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T16:35:27.578Z,1690907727.578 [Default:CheckIn] Running Loop=1
2023-08-01T16:35:27.579Z,1690907727.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T16:35:27.579Z,1690907727.579 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T16:35:28.010Z,1690907728.010 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-01T16:35:32.336Z,1690907732.336 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2023-08-01T16:35:32.337Z,1690907732.337 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 96.000000 %
2023-08-01T16:37:06.252Z,1690907826.252 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-01T16:37:21.196Z,1690907841.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163657.00,A,4313.60720,N,08620.36668,W,2.294,56.35,010823,,,A*4B
2023-08-01T16:37:21.199Z,1690907841.199 [NAL9602](INFO): GPS fix at 20230801T163657: (43.226787, -86.339445)
2023-08-01T16:37:21.220Z,1690907841.220 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T16:37:21.220Z,1690907841.220 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T16:37:21.667Z,1690907841.667 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-01T16:37:23.456Z,1690907843.456 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230801T163112/Courier0000.lzma
2023-08-01T16:37:23.894Z,1690907843.894 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Courier0000.lzma.bak
2023-08-01T16:37:23.894Z,1690907843.894 [DataOverHttps](INFO): SBD MOMSN=18505150
2023-08-01T16:37:41.188Z,1690907861.188 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230801T163112/Courier0004.lzma
2023-08-01T16:37:42.190Z,1690907862.190 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Courier0004.lzma.bak
2023-08-01T16:37:42.190Z,1690907862.190 [DataOverHttps](INFO): SBD MOMSN=18505153
2023-08-01T16:37:53.349Z,1690907873.349 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T16:38:01.035Z,1690907881.035 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20230801T161649/Express0011.lzma
2023-08-01T16:38:02.038Z,1690907882.038 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Express0011.lzma.bak
2023-08-01T16:38:02.038Z,1690907882.038 [DataOverHttps](INFO): SBD MOMSN=18505156
2023-08-01T16:38:17.528Z,1690907897.528 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-08-01T16:38:21.090Z,1690907901.090 [DataOverHttps](INFO): Sending 1113 bytes from file Logs/20230801T163112/Express0001.lzma
2023-08-01T16:38:22.090Z,1690907902.090 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0001.lzma.bak
2023-08-01T16:38:22.090Z,1690907902.090 [DataOverHttps](INFO): SBD MOMSN=18505159
2023-08-01T16:38:38.924Z,1690907918.924 [DataOverHttps](INFO): Sending 383 bytes from file Logs/20230801T163112/Express0005.lzma
2023-08-01T16:38:39.926Z,1690907919.926 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0005.lzma.bak
2023-08-01T16:38:39.926Z,1690907919.926 [DataOverHttps](INFO): SBD MOMSN=18505191
2023-08-01T16:38:42.171Z,1690907922.171 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T16:38:42.171Z,1690907922.171 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T16:38:42.171Z,1690907922.171 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:39:03.217Z,1690907943.217 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-01T16:39:03.233Z,1690907943.233 [BPC1](INFO): Received data from all battery sticks.
2023-08-01T16:41:27.935Z,1690908087.935 [Sonardyne_Nano](INFO): Command indicated, sending VS
2023-08-01T16:41:28.342Z,1690908088.342 [Sonardyne_Nano](INFO): Received response:>VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.6

2023-08-01T16:41:28.342Z,1690908088.342 [Sonardyne_Nano](INFO): Parsing volatile status
2023-08-01T16:41:28.342Z,1690908088.342 [Sonardyne_Nano](INFO): Parsing charge percentage, response: >VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.6

2023-08-01T16:41:28.342Z,1690908088.342 [Sonardyne_Nano](INFO): Found fwd slash
2023-08-01T16:41:28.343Z,1690908088.343 [Sonardyne_Nano](INFO): Charge percentage: 96
2023-08-01T16:41:28.343Z,1690908088.343 [Sonardyne_Nano](INFO): Writing last charge 96 2023-08-01T16:41:27.934Z
2023-08-01T16:41:51.369Z,1690908111.369 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2023-08-01T16:42:51.241Z,1690908171.241 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T16:42:51.242Z,1690908171.242 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+25.5,489.0,000
2023-08-01T16:43:42.593Z,1690908222.593 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T16:43:42.593Z,1690908222.593 [Default:CheckIn:C.Wait] Stopped
2023-08-01T16:43:42.593Z,1690908222.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T16:43:42.593Z,1690908222.593 [Default:CheckIn:D] Running Loop=1
2023-08-01T16:43:42.974Z,1690908222.974 [Default:CheckIn:D] Stopped
2023-08-01T16:43:42.974Z,1690908222.974 [Default:CheckIn:E] Running Loop=1
2023-08-01T16:43:43.375Z,1690908223.375 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.484574 min 
2023-08-01T16:43:43.376Z,1690908223.376 [Default:CheckIn:E] Stopped
2023-08-01T16:43:43.376Z,1690908223.376 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T16:43:43.376Z,1690908223.376 [Default:CheckIn] Stopped
2023-08-01T16:43:43.376Z,1690908223.376 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T16:43:43.376Z,1690908223.376 [Default:CheckIn](INFO): Running loop #2
2023-08-01T16:43:43.376Z,1690908223.376 [Default:CheckIn] Running Loop=2
2023-08-01T16:43:43.376Z,1690908223.376 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T16:43:43.377Z,1690908223.377 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T16:43:45.397Z,1690908225.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164321.00,A,4313.61065,N,08620.34610,W,0.389,46.01,010823,,,A*4B
2023-08-01T16:43:45.399Z,1690908225.399 [NAL9602](INFO): GPS fix at 20230801T164321: (43.226844, -86.339102)
2023-08-01T16:43:45.410Z,1690908225.410 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T16:43:45.410Z,1690908225.410 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T16:43:53.240Z,1690908233.240 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230801T163112/Courier0007.lzma
2023-08-01T16:43:54.242Z,1690908234.242 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Courier0007.lzma.bak
2023-08-01T16:43:54.242Z,1690908234.242 [DataOverHttps](INFO): SBD MOMSN=18505198
2023-08-01T16:44:14.991Z,1690908254.991 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20230801T163112/Express0008.lzma
2023-08-01T16:44:15.822Z,1690908255.822 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-01T16:44:15.904Z,1690908255.904 [NAL9602](ERROR): received: 
+CSQ:1

OK66, 2, 0, 0, 0

OK
2023-08-01T16:44:15.994Z,1690908255.994 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0008.lzma.bak
2023-08-01T16:44:15.994Z,1690908255.994 [DataOverHttps](INFO): SBD MOMSN=18505201
2023-08-01T16:44:18.266Z,1690908258.266 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T16:44:18.266Z,1690908258.266 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T16:44:18.266Z,1690908258.266 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:44:44.129Z,1690908284.129 [NAL9602](INFO): SBD MO Status=2, MOMSN=7066, MT Status=2, MTMSN=0
2023-08-01T16:44:44.129Z,1690908284.129 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:46:04.502Z,1690908364.502 [DVL_micro](ERROR): only read 2 of 4 data items
2023-08-01T16:46:04.502Z,1690908364.502 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,,+9999.99
2023-08-01T16:46:09.753Z,1690908369.753 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.0000.00,+00000000.00,9999.99,000.00
2023-08-01T16:46:33.586Z,1690908393.586 [NAL9602](INFO): SBD MO Status=0, MOMSN=7066, MT Status=0, MTMSN=0
2023-08-01T16:46:33.586Z,1690908393.586 [NAL9602](INFO): No messages in MT queue
2023-08-01T16:46:39.646Z,1690908399.646 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T16:46:39.646Z,1690908399.646 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+2.0,1489.0,000
2023-08-01T16:47:04.290Z,1690908424.290 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T16:48:17.823Z,1690908497.823 [DVL_micro](ERROR): Failed to parse:
:WI,+01119,+00938,+00242,+00000,A
2023-08-01T16:49:18.831Z,1690908558.831 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T16:49:18.831Z,1690908558.831 [Default:CheckIn:C.Wait] Stopped
2023-08-01T16:49:18.831Z,1690908558.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T16:49:18.832Z,1690908558.832 [Default:CheckIn:D] Running Loop=1
2023-08-01T16:49:19.241Z,1690908559.241 [Default:CheckIn:D] Stopped
2023-08-01T16:49:19.241Z,1690908559.241 [Default:CheckIn:E] Running Loop=1
2023-08-01T16:49:19.655Z,1690908559.655 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.089018 min 
2023-08-01T16:49:19.655Z,1690908559.655 [Default:CheckIn:E] Stopped
2023-08-01T16:49:19.655Z,1690908559.655 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T16:49:19.655Z,1690908559.655 [Default:CheckIn] Stopped
2023-08-01T16:49:19.655Z,1690908559.655 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T16:49:19.655Z,1690908559.655 [Default:CheckIn](INFO): Running loop #3
2023-08-01T16:49:19.655Z,1690908559.655 [Default:CheckIn] Running Loop=3
2023-08-01T16:49:19.660Z,1690908559.660 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T16:49:19.660Z,1690908559.660 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T16:49:21.655Z,1690908561.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164900.00,A,4313.61327,N,08620.34082,W,0.369,62.73,010823,,,A*47
2023-08-01T16:49:21.661Z,1690908561.661 [NAL9602](INFO): GPS fix at 20230801T164900: (43.226888, -86.339014)
2023-08-01T16:49:21.671Z,1690908561.671 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T16:49:21.671Z,1690908561.671 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T16:49:29.599Z,1690908569.599 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230801T163112/Courier0010.lzma
2023-08-01T16:49:30.602Z,1690908570.602 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Courier0010.lzma.bak
2023-08-01T16:49:30.602Z,1690908570.602 [DataOverHttps](INFO): SBD MOMSN=18505205
2023-08-01T16:49:46.611Z,1690908586.611 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230801T163112/Express0011.lzma
2023-08-01T16:49:47.614Z,1690908587.614 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0011.lzma.bak
2023-08-01T16:49:47.614Z,1690908587.614 [DataOverHttps](INFO): SBD MOMSN=18505208
2023-08-01T16:49:49.540Z,1690908589.540 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T16:49:49.541Z,1690908589.541 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T16:49:49.541Z,1690908589.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:49:55.697Z,1690908595.697 [DVL_micro](ERROR): Failed to parse:99.99,+9999.99,+9999.99,+9999.99
2023-08-01T16:49:57.245Z,1690908597.245 [NAL9602](INFO): SBD MO Status=2, MOMSN=7067, MT Status=2, MTMSN=0
2023-08-01T16:49:57.246Z,1690908597.246 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:50:40.870Z,1690908640.870 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T16:50:40.871Z,1690908640.871 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+25.8,0000.,000
2023-08-01T16:51:28.166Z,1690908688.166 [Sonardyne_Nano](INFO): Command indicated, sending VS
2023-08-01T16:51:28.579Z,1690908688.579 [Sonardyne_Nano](INFO): Received response:>VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-12.0;CAP3.4/96;T25.6

2023-08-01T16:51:28.581Z,1690908688.581 [Sonardyne_Nano](INFO): Parsing volatile status
2023-08-01T16:51:28.581Z,1690908688.581 [Sonardyne_Nano](INFO): Parsing charge percentage, response: >VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-12.0;CAP3.4/96;T25.6

2023-08-01T16:51:28.581Z,1690908688.581 [Sonardyne_Nano](INFO): Found fwd slash
2023-08-01T16:51:28.581Z,1690908688.581 [Sonardyne_Nano](INFO): Charge percentage: 96
2023-08-01T16:51:28.582Z,1690908688.582 [Sonardyne_Nano](INFO): Writing last charge 96 2023-08-01T16:51:28.166Z
2023-08-01T16:54:23.292Z,1690908863.292 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-01T16:54:39.905Z,1690908879.905 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T16:54:39.906Z,1690908879.906 [DVL_micro](ERROR): Failed to parse:
:BI,01174,+00323,+00000,I
2023-08-01T16:54:49.963Z,1690908889.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T16:54:49.980Z,1690908889.980 [Default:CheckIn:C.Wait] Stopped
2023-08-01T16:54:49.980Z,1690908889.980 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T16:54:49.980Z,1690908889.980 [Default:CheckIn:D] Running Loop=1
2023-08-01T16:54:50.362Z,1690908890.362 [Default:CheckIn:D] Stopped
2023-08-01T16:54:50.363Z,1690908890.363 [Default:CheckIn:E] Running Loop=1
2023-08-01T16:54:50.768Z,1690908890.768 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.607717 min 
2023-08-01T16:54:50.768Z,1690908890.768 [Default:CheckIn:E] Stopped
2023-08-01T16:54:50.768Z,1690908890.768 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T16:54:50.768Z,1690908890.768 [Default:CheckIn] Stopped
2023-08-01T16:54:50.768Z,1690908890.768 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T16:54:50.769Z,1690908890.769 [Default:CheckIn](INFO): Running loop #4
2023-08-01T16:54:50.769Z,1690908890.769 [Default:CheckIn] Running Loop=4
2023-08-01T16:54:50.769Z,1690908890.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T16:54:50.769Z,1690908890.769 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T16:54:52.790Z,1690908892.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165431.00,A,4313.61470,N,08620.33982,W,0.019,62.08,010823,,,A*4A
2023-08-01T16:54:52.792Z,1690908892.792 [NAL9602](INFO): GPS fix at 20230801T165431: (43.226912, -86.338997)
2023-08-01T16:54:52.803Z,1690908892.803 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T16:54:52.803Z,1690908892.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T16:55:02.720Z,1690908902.720 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20230801T163112/Courier0013.lzma
2023-08-01T16:55:03.722Z,1690908903.722 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Courier0013.lzma.bak
2023-08-01T16:55:03.722Z,1690908903.722 [DataOverHttps](INFO): SBD MOMSN=18505211
2023-08-01T16:55:21.180Z,1690908921.180 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20230801T163112/Express0014.lzma
2023-08-01T16:55:22.182Z,1690908922.182 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0014.lzma.bak
2023-08-01T16:55:22.182Z,1690908922.182 [DataOverHttps](INFO): SBD MOMSN=18505215
2023-08-01T16:55:24.490Z,1690908924.490 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T16:55:24.490Z,1690908924.490 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T16:55:24.490Z,1690908924.490 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:55:24.919Z,1690908924.919 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T16:56:02.838Z,1690908962.838 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+0000000000000.00,9999.99,000.00
2023-08-01T17:00:25.055Z,1690909225.055 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:00:25.055Z,1690909225.055 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:00:25.055Z,1690909225.055 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:00:25.055Z,1690909225.055 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:00:25.466Z,1690909225.466 [Default:CheckIn:D] Stopped
2023-08-01T17:00:25.466Z,1690909225.466 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:00:25.879Z,1690909225.879 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.192780 min 
2023-08-01T17:00:25.879Z,1690909225.879 [Default:CheckIn:E] Stopped
2023-08-01T17:00:25.879Z,1690909225.879 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:00:25.879Z,1690909225.879 [Default:CheckIn] Stopped
2023-08-01T17:00:25.879Z,1690909225.879 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:00:25.880Z,1690909225.880 [Default:CheckIn](INFO): Running loop #5
2023-08-01T17:00:25.880Z,1690909225.880 [Default:CheckIn] Running Loop=5
2023-08-01T17:00:25.880Z,1690909225.880 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:00:25.880Z,1690909225.880 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:00:27.874Z,1690909227.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170006.00,A,4313.61426,N,08620.33924,W,0.194,62.08,010823,,,A*45
2023-08-01T17:00:27.876Z,1690909227.876 [NAL9602](INFO): GPS fix at 20230801T170006: (43.226904, -86.338987)
2023-08-01T17:00:27.887Z,1690909227.887 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:00:27.887Z,1690909227.887 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:00:34.767Z,1690909234.767 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230801T163112/Courier0016.lzma
2023-08-01T17:00:35.770Z,1690909235.770 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Courier0016.lzma.bak
2023-08-01T17:00:35.770Z,1690909235.770 [DataOverHttps](INFO): SBD MOMSN=18505219
2023-08-01T17:00:52.576Z,1690909252.576 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230801T163112/Express0017.lzma
2023-08-01T17:00:53.578Z,1690909253.578 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0017.lzma.bak
2023-08-01T17:00:53.578Z,1690909253.578 [DataOverHttps](INFO): SBD MOMSN=18505222
2023-08-01T17:00:56.291Z,1690909256.291 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:00:56.291Z,1690909256.291 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:00:56.291Z,1690909256.291 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:00:58.640Z,1690909258.640 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-01T17:00:58.724Z,1690909258.724 [NAL9602](ERROR): received: 
+CSQ:0

OK67, 2, 0, 0, 0

OK
2023-08-01T17:01:28.209Z,1690909288.209 [Sonardyne_Nano](INFO): Command indicated, sending VS
2023-08-01T17:01:28.614Z,1690909288.614 [Sonardyne_Nano](INFO): Received response:>VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.6

2023-08-01T17:01:28.614Z,1690909288.614 [Sonardyne_Nano](INFO): Parsing volatile status
2023-08-01T17:01:28.614Z,1690909288.614 [Sonardyne_Nano](INFO): Parsing charge percentage, response: >VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.6

2023-08-01T17:01:28.614Z,1690909288.614 [Sonardyne_Nano](INFO): Found fwd slash
2023-08-01T17:01:28.615Z,1690909288.615 [Sonardyne_Nano](INFO): Charge percentage: 96
2023-08-01T17:01:28.615Z,1690909288.615 [Sonardyne_Nano](INFO): Writing last charge 96 2023-08-01T17:01:28.209Z
2023-08-01T17:03:35.454Z,1690909415.454 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 1155
2023-08-01T17:03:35.456Z,1690909415.456 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2023-08-01T17:03:40.366Z,1690909420.366 [NAL9602](INFO): SBD MO Status=2, MOMSN=7067, MT Status=2, MTMSN=0
2023-08-01T17:03:40.366Z,1690909420.366 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:03:59.708Z,1690909439.708 [NAL9602](INFO): SBD MO Status=0, MOMSN=7067, MT Status=0, MTMSN=0
2023-08-01T17:03:59.708Z,1690909439.708 [NAL9602](INFO): No messages in MT queue
2023-08-01T17:04:30.420Z,1690909470.420 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:05:56.886Z,1690909556.886 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:05:56.886Z,1690909556.886 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:05:56.886Z,1690909556.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:05:56.886Z,1690909556.886 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:05:57.288Z,1690909557.288 [Default:CheckIn:D] Stopped
2023-08-01T17:05:57.288Z,1690909557.288 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:05:57.702Z,1690909557.702 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.723149 min 
2023-08-01T17:05:57.702Z,1690909557.702 [Default:CheckIn:E] Stopped
2023-08-01T17:05:57.703Z,1690909557.703 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:05:57.703Z,1690909557.703 [Default:CheckIn] Stopped
2023-08-01T17:05:57.703Z,1690909557.703 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:05:57.703Z,1690909557.703 [Default:CheckIn](INFO): Running loop #6
2023-08-01T17:05:57.703Z,1690909557.703 [Default:CheckIn] Running Loop=6
2023-08-01T17:05:57.703Z,1690909557.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:05:57.703Z,1690909557.703 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:05:59.699Z,1690909559.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170538.00,A,4313.61600,N,08620.34037,W,0.175,58.98,010823,,,A*48
2023-08-01T17:05:59.706Z,1690909559.706 [NAL9602](INFO): GPS fix at 20230801T170538: (43.226933, -86.339006)
2023-08-01T17:05:59.758Z,1690909559.758 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:05:59.758Z,1690909559.758 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:06:07.059Z,1690909567.059 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230801T163112/Courier0019.lzma
2023-08-01T17:06:08.062Z,1690909568.062 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Courier0019.lzma.bak
2023-08-01T17:06:08.062Z,1690909568.062 [DataOverHttps](INFO): SBD MOMSN=18505225
2023-08-01T17:06:24.644Z,1690909584.644 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230801T163112/Express0020.lzma
2023-08-01T17:06:25.551Z,1690909585.551 [NAL9602](INFO): SBD MO Status=0, MOMSN=7068, MT Status=0, MTMSN=0
2023-08-01T17:06:25.551Z,1690909585.551 [NAL9602](INFO): No messages in MT queue
2023-08-01T17:06:25.997Z,1690909585.997 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0020.lzma.bak
2023-08-01T17:06:25.997Z,1690909585.997 [DataOverHttps](INFO): SBD MOMSN=18505229
2023-08-01T17:06:27.579Z,1690909587.579 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:06:27.579Z,1690909587.579 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:06:27.579Z,1690909587.579 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:06:56.252Z,1690909616.252 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:09:45.565Z,1690909785.565 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2023-08-01T17:09:57.209Z,1690909797.209 [CommandExec](IMPORTANT): got command restart system
2023-08-01T17:09:59.284Z,1690909799.284 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-08-01T17:09:59.284Z,1690909799.284 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:09:59.284Z,1690909799.284 [CommandExec](INFO): Uninitializing the command executive.
2023-08-01T17:09:59.285Z,1690909799.285 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-01T17:09:59.285Z,1690909799.285 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:09:59.428Z,1690909799.428 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-01T17:09:59.428Z,1690909799.428 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-01T17:09:59.429Z,1690909799.429 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:09:59.429Z,1690909799.429 [NavChartDb](INFO): Join timeout helper Thread ID is 1667
2023-08-01T17:09:59.508Z,1690909799.508 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:09:59.509Z,1690909799.509 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:09:59.524Z,1690909799.524 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-01T17:09:59.524Z,1690909799.524 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:09:59.524Z,1690909799.524 [Radio_Surface](INFO): Join timeout helper Thread ID is 1668
2023-08-01T17:09:59.892Z,1690909799.892 [Radio_Surface](INFO): Powering down
2023-08-01T17:09:59.893Z,1690909799.893 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:09:59.893Z,1690909799.893 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:09:59.902Z,1690909799.902 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-01T17:09:59.902Z,1690909799.902 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:09:59.903Z,1690909799.903 [Onboard](INFO): Join timeout helper Thread ID is 1669
2023-08-01T17:10:00.636Z,1690909800.636 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:00.637Z,1690909800.637 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.645Z,1690909800.645 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-01T17:10:00.645Z,1690909800.645 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.647Z,1690909800.647 [DataOverHttps](INFO): Join timeout helper Thread ID is 1670
2023-08-01T17:10:00.788Z,1690909800.788 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:00.788Z,1690909800.788 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.792Z,1690909800.792 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-08-01T17:10:00.792Z,1690909800.792 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.792Z,1690909800.792 [BackseatComponent](INFO): Join timeout helper Thread ID is 1671
2023-08-01T17:10:00.800Z,1690909800.800 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:00.800Z,1690909800.800 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.845Z,1690909800.845 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2023-08-01T17:10:00.845Z,1690909800.845 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.846Z,1690909800.846 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1672
2023-08-01T17:10:00.876Z,1690909800.876 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:00.876Z,1690909800.876 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.889Z,1690909800.889 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-01T17:10:00.889Z,1690909800.889 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:00.889Z,1690909800.889 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1673
2023-08-01T17:10:01.224Z,1690909801.224 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:01.224Z,1690909801.224 [WetLabsBB2FL](INFO): Powering down
2023-08-01T17:10:01.225Z,1690909801.225 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.245Z,1690909801.245 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-01T17:10:01.245Z,1690909801.245 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.245Z,1690909801.245 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1674
2023-08-01T17:10:01.724Z,1690909801.724 [CTD_Seabird](INFO): Powering down
2023-08-01T17:10:01.736Z,1690909801.736 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:01.736Z,1690909801.736 [CTD_Seabird](INFO): Powering down
2023-08-01T17:10:01.748Z,1690909801.748 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.761Z,1690909801.761 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-01T17:10:01.761Z,1690909801.761 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.761Z,1690909801.761 [logger](INFO): Join timeout helper Thread ID is 1675
2023-08-01T17:10:01.812Z,1690909801.812 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:01.813Z,1690909801.813 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.828Z,1690909801.828 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-01T17:10:01.829Z,1690909801.829 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.829Z,1690909801.829 [CommandLine](INFO): Join timeout helper Thread ID is 1676
2023-08-01T17:10:01.840Z,1690909801.840 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:01.840Z,1690909801.840 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.848Z,1690909801.848 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-01T17:10:01.849Z,1690909801.849 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.849Z,1690909801.849 [CommandExec](INFO): Join timeout helper Thread ID is 1677
2023-08-01T17:10:01.850Z,1690909801.850 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-01T17:10:01.850Z,1690909801.850 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:01.851Z,1690909801.851 [controlThread](INFO): Join timeout helper Thread ID is 1678
2023-08-01T17:10:02.080Z,1690909802.080 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T17:10:02.080Z,1690909802.080 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-01T17:10:02.083Z,1690909802.083 [AHRS_M2](INFO): Powering down
2023-08-01T17:10:02.228Z,1690909802.228 [DVL_micro](INFO): Powering down
2023-08-01T17:10:02.229Z,1690909802.229 [NAL9602](INFO): Powering down
2023-08-01T17:10:02.230Z,1690909802.230 [Sonardyne_Nano](INFO): Uninitializing.
2023-08-01T17:10:02.231Z,1690909802.231 [Sonardyne_Nano](INFO): Powering down
2023-08-01T17:10:02.348Z,1690909802.348 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-01T17:10:02.349Z,1690909802.349 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-01T17:10:02.350Z,1690909802.350 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-01T17:10:02.350Z,1690909802.350 [MissionManager](INFO): Uninitializing Mission Default
2023-08-01T17:10:02.350Z,1690909802.350 [Default] Stopped
2023-08-01T17:10:02.351Z,1690909802.351 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-01T17:10:02.351Z,1690909802.351 [Default:B.GoToSurface] Stopped
2023-08-01T17:10:02.351Z,1690909802.351 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-01T17:10:02.351Z,1690909802.351 [Default:CheckIn] Stopped
2023-08-01T17:10:02.351Z,1690909802.351 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:10:02.351Z,1690909802.351 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:10:02.351Z,1690909802.351 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:10:02.354Z,1690909802.354 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-01T17:10:02.354Z,1690909802.354 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-01T17:10:02.355Z,1690909802.355 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-01T17:10:02.355Z,1690909802.355 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-01T17:10:02.355Z,1690909802.355 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-01T17:10:02.355Z,1690909802.355 [BuoyancyServo](INFO): Powering down
2023-08-01T17:10:02.368Z,1690909802.368 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-01T17:10:02.368Z,1690909802.368 [ElevatorServo](INFO): Powering down
2023-08-01T17:10:02.369Z,1690909802.369 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-01T17:10:02.369Z,1690909802.369 [MassServo](INFO): Powering down
2023-08-01T17:10:02.370Z,1690909802.370 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-01T17:10:02.370Z,1690909802.370 [RudderServo](INFO): Powering down
2023-08-01T17:10:02.371Z,1690909802.371 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-01T17:10:02.371Z,1690909802.371 [ThrusterHE](INFO): Powering down
2023-08-01T17:10:02.372Z,1690909802.372 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-01T17:10:02.373Z,1690909802.373 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-01T17:10:02.373Z,1690909802.373 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-01T17:10:02.373Z,1690909802.373 [CBIT](DEBUG): Powering off loads.
2023-08-01T17:10:02.384Z,1690909802.384 [CBIT](DEBUG): Disabling WDT.
2023-08-01T17:10:02.396Z,1690909802.396 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-01T17:10:02.397Z,1690909802.397 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.450Z,1690909802.450 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.453Z,1690909802.453 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.459Z,1690909802.459 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.498Z,1690909802.498 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.500Z,1690909802.500 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.508Z,1690909802.508 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.555Z,1690909802.555 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.621Z,1690909802.621 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T17:10:02.685Z,1690909802.685 [logger ThreadHandler](INFO): Thread cancelled.