2023-08-01T17:11:22.777Z,1690909882.777 [Supervisor](DEBUG): Initializing supervisor.
2023-08-01T17:11:22.781Z,1690909882.781 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-01T17:11:22.782Z,1690909882.782 [SyncHandler](INFO): Protected caller Thread ID is 832
2023-08-01T17:11:22.782Z,1690909882.782 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-01T17:11:22.783Z,1690909882.783 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-01T17:11:22.783Z,1690909882.783 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 833
2023-08-01T17:11:22.787Z,1690909882.787 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-01T17:11:22.805Z,1690909882.805 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-01T17:11:22.806Z,1690909882.806 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-01T17:11:22.807Z,1690909882.807 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 834
2023-08-01T17:11:22.811Z,1690909882.811 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-01T17:11:22.812Z,1690909882.812 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-01T17:11:22.812Z,1690909882.812 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 835
2023-08-01T17:11:22.814Z,1690909882.814 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-01T17:11:22.815Z,1690909882.815 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-01T17:11:22.816Z,1690909882.816 [logger ThreadHandler](INFO): Protected caller Thread ID is 836
2023-08-01T17:11:22.819Z,1690909882.819 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-01T17:11:22.820Z,1690909882.820 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-01T17:11:22.825Z,1690909882.825 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-01T17:11:22.923Z,1690909882.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-01T17:11:22.925Z,1690909882.925 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-01T17:11:23.149Z,1690909883.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-01T17:11:23.151Z,1690909883.151 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-01T17:11:23.413Z,1690909883.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-01T17:11:23.413Z,1690909883.413 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-01T17:11:23.523Z,1690909883.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-01T17:11:23.525Z,1690909883.525 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-01T17:11:24.275Z,1690909884.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-01T17:11:24.277Z,1690909884.277 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-01T17:11:24.784Z,1690909884.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-01T17:11:24.785Z,1690909884.785 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-01T17:11:25.282Z,1690909885.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-01T17:11:25.284Z,1690909885.284 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-01T17:11:25.694Z,1690909885.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-01T17:11:25.695Z,1690909885.695 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-01T17:11:26.051Z,1690909886.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-01T17:11:26.051Z,1690909886.051 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-01T17:11:27.028Z,1690909887.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-01T17:11:27.029Z,1690909887.029 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-01T17:11:27.254Z,1690909887.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-01T17:11:27.256Z,1690909887.256 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-01T17:11:27.398Z,1690909887.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-01T17:11:27.400Z,1690909887.400 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-01T17:11:27.483Z,1690909887.483 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-01T17:11:27.562Z,1690909887.562 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-01T17:11:27.752Z,1690909887.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-01T17:11:27.753Z,1690909887.753 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-01T17:11:27.986Z,1690909887.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-01T17:11:27.988Z,1690909887.988 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-08-01T17:11:27.991Z,1690909887.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-08-01T17:11:28.079Z,1690909888.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-08-01T17:11:28.349Z,1690909888.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-01T17:11:28.350Z,1690909888.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-08-01T17:11:28.458Z,1690909888.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-08-01T17:11:28.622Z,1690909888.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-08-01T17:11:28.724Z,1690909888.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-08-01T17:11:28.838Z,1690909888.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-08-01T17:11:28.950Z,1690909888.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-08-01T17:11:29.056Z,1690909889.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-08-01T17:11:29.220Z,1690909889.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2023-08-01T17:11:29.329Z,1690909889.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-08-01T17:11:29.576Z,1690909889.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-08-01T17:11:30.199Z,1690909890.199 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-08-01T17:11:30.222Z,1690909890.222 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-01T17:11:30.417Z,1690909890.417 [InternalEnvSim] Loaded
2023-08-01T17:11:30.417Z,1690909890.417 [ComponentRegistry](DEBUG): SyncComponent "InternalEnvSim" handled in the control thread.
2023-08-01T17:11:30.418Z,1690909890.418 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-01T17:11:30.418Z,1690909890.418 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-01T17:11:30.601Z,1690909890.601 [BuoyancyServo] Loaded
2023-08-01T17:11:30.602Z,1690909890.602 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-01T17:11:30.623Z,1690909890.623 [ElevatorServo] Loaded
2023-08-01T17:11:30.623Z,1690909890.623 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-01T17:11:30.644Z,1690909890.644 [MassServo] Loaded
2023-08-01T17:11:30.644Z,1690909890.644 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-01T17:11:30.665Z,1690909890.665 [RudderServo] Loaded
2023-08-01T17:11:30.665Z,1690909890.665 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-01T17:11:30.681Z,1690909890.681 [ThrusterHE] Loaded
2023-08-01T17:11:30.681Z,1690909890.681 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-01T17:11:30.682Z,1690909890.682 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-01T17:11:30.684Z,1690909890.684 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-01T17:11:30.854Z,1690909890.854 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-01T17:11:30.869Z,1690909890.869 [SBIT] Loaded
2023-08-01T17:11:30.869Z,1690909890.869 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-01T17:11:30.872Z,1690909890.872 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-01T17:11:30.894Z,1690909890.894 [IBIT] Loaded
2023-08-01T17:11:30.894Z,1690909890.894 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-01T17:11:30.900Z,1690909890.900 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-01T17:11:31.121Z,1690909891.121 [CBIT] Loaded
2023-08-01T17:11:31.121Z,1690909891.121 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-01T17:11:31.121Z,1690909891.121 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-01T17:11:31.122Z,1690909891.122 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-01T17:11:31.724Z,1690909891.724 [CTD_Seabird] Loaded
2023-08-01T17:11:31.725Z,1690909891.725 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-01T17:11:31.726Z,1690909891.726 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406674E0
2023-08-01T17:11:31.726Z,1690909891.726 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 921
2023-08-01T17:11:31.779Z,1690909891.779 [ESPComponent] Loaded
2023-08-01T17:11:31.779Z,1690909891.779 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-08-01T17:11:31.834Z,1690909891.834 [PAR_Licor] Loaded
2023-08-01T17:11:31.834Z,1690909891.834 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-01T17:11:31.897Z,1690909891.897 [WetLabsBB2FL] Loaded
2023-08-01T17:11:31.897Z,1690909891.897 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-01T17:11:31.898Z,1690909891.898 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406974E0
2023-08-01T17:11:31.898Z,1690909891.898 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 922
2023-08-01T17:11:31.929Z,1690909891.929 [WetLabsSeaOWL_UV_A] Loaded
2023-08-01T17:11:31.929Z,1690909891.929 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2023-08-01T17:11:31.930Z,1690909891.930 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406C74E0
2023-08-01T17:11:31.930Z,1690909891.930 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 923
2023-08-01T17:11:31.931Z,1690909891.931 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-01T17:11:31.932Z,1690909891.932 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-01T17:11:32.237Z,1690909892.237 [DepthRateCalculator] Loaded
2023-08-01T17:11:32.237Z,1690909892.237 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-01T17:11:32.243Z,1690909892.243 [PitchRateCalculator] Loaded
2023-08-01T17:11:32.243Z,1690909892.243 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-01T17:11:32.254Z,1690909892.254 [SpeedCalculator] Loaded
2023-08-01T17:11:32.254Z,1690909892.254 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-01T17:11:32.259Z,1690909892.259 [YawRateCalculator] Loaded
2023-08-01T17:11:32.259Z,1690909892.259 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-01T17:11:32.279Z,1690909892.279 [ElevatorOffsetCalculator] Loaded
2023-08-01T17:11:32.280Z,1690909892.280 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-01T17:11:32.297Z,1690909892.297 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-08-01T17:11:32.297Z,1690909892.297 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-08-01T17:11:32.297Z,1690909892.297 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-01T17:11:32.299Z,1690909892.299 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-01T17:11:32.425Z,1690909892.425 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-01T17:11:32.426Z,1690909892.426 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-01T17:11:32.469Z,1690909892.469 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-01T17:11:32.470Z,1690909892.470 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-01T17:11:32.603Z,1690909892.603 [DeadReckonUsingSpeedCalculator] Loaded
2023-08-01T17:11:32.603Z,1690909892.603 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-08-01T17:11:32.617Z,1690909892.617 [NavChart] Loaded
2023-08-01T17:11:32.618Z,1690909892.618 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-01T17:11:32.623Z,1690909892.623 [UniversalFixResidualReporter] Loaded
2023-08-01T17:11:32.623Z,1690909892.624 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-01T17:11:32.624Z,1690909892.624 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-01T17:11:32.625Z,1690909892.625 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-01T17:11:33.433Z,1690909893.433 [AHRS_M2] Loaded
2023-08-01T17:11:33.433Z,1690909893.433 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-01T17:11:33.682Z,1690909893.682 [BackseatComponent] Loaded
2023-08-01T17:11:33.682Z,1690909893.682 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-01T17:11:33.683Z,1690909893.683 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A5C4E0
2023-08-01T17:11:33.684Z,1690909893.684 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2023-08-01T17:11:33.686Z,1690909893.686 [LcmUniversalReporter] Loaded
2023-08-01T17:11:33.687Z,1690909893.687 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-01T17:11:34.431Z,1690909894.431 [BPC1] Loaded
2023-08-01T17:11:34.431Z,1690909894.431 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-01T17:11:34.574Z,1690909894.574 [DataOverHttps] Loaded
2023-08-01T17:11:34.575Z,1690909894.575 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-01T17:11:34.576Z,1690909894.576 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A8C4E0
2023-08-01T17:11:34.576Z,1690909894.576 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2023-08-01T17:11:34.597Z,1690909894.597 [Depth_Keller] Loaded
2023-08-01T17:11:34.597Z,1690909894.597 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-01T17:11:34.603Z,1690909894.603 [DropWeight] Loaded
2023-08-01T17:11:34.603Z,1690909894.603 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-01T17:11:34.643Z,1690909894.643 [DVL_micro] Loaded
2023-08-01T17:11:34.643Z,1690909894.643 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-08-01T17:11:34.704Z,1690909894.704 [NAL9602] Loaded
2023-08-01T17:11:34.704Z,1690909894.704 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-01T17:11:34.731Z,1690909894.731 [Onboard] Loaded
2023-08-01T17:11:34.731Z,1690909894.731 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-01T17:11:34.732Z,1690909894.732 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40ABC4E0
2023-08-01T17:11:34.732Z,1690909894.732 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2023-08-01T17:11:34.745Z,1690909894.745 [Power24vConverter] Loaded
2023-08-01T17:11:34.745Z,1690909894.745 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-01T17:11:34.758Z,1690909894.758 [Radio_Surface] Loaded
2023-08-01T17:11:34.759Z,1690909894.759 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-01T17:11:34.760Z,1690909894.760 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0
2023-08-01T17:11:34.760Z,1690909894.760 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2023-08-01T17:11:34.774Z,1690909894.774 [Sonardyne_Nano] Loaded
2023-08-01T17:11:34.774Z,1690909894.774 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-08-01T17:11:34.775Z,1690909894.775 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-01T17:11:34.777Z,1690909894.777 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-01T17:11:34.860Z,1690909894.860 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-01T17:11:34.862Z,1690909894.862 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-01T17:11:34.931Z,1690909894.931 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-01T17:11:34.982Z,1690909894.982 [VerticalControl] Loaded
2023-08-01T17:11:34.983Z,1690909894.983 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-01T17:11:34.986Z,1690909894.986 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-01T17:11:35.024Z,1690909895.024 [HorizontalControl] Loaded
2023-08-01T17:11:35.024Z,1690909895.024 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-01T17:11:35.027Z,1690909895.027 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-01T17:11:35.029Z,1690909895.029 [SpeedControl] Loaded
2023-08-01T17:11:35.029Z,1690909895.029 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-01T17:11:35.032Z,1690909895.032 [LoopControl](DEBUG): Construct LoopControl.
2023-08-01T17:11:35.032Z,1690909895.032 [LoopControl] Loaded
2023-08-01T17:11:35.032Z,1690909895.033 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-01T17:11:35.033Z,1690909895.033 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-01T17:11:35.034Z,1690909895.034 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-01T17:11:35.049Z,1690909895.049 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-01T17:11:35.050Z,1690909895.050 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-01T17:11:35.402Z,1690909895.402 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-01T17:11:35.408Z,1690909895.408 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-01T17:11:35.411Z,1690909895.411 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-01T17:11:35.422Z,1690909895.422 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-01T17:11:35.423Z,1690909895.423 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF84E0
2023-08-01T17:11:35.424Z,1690909895.424 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2023-08-01T17:11:35.428Z,1690909895.428 [Supervisor](INFO): Main Thread ID is 824
2023-08-01T17:11:35.428Z,1690909895.428 [Supervisor](DEBUG): Running supervisor.
2023-08-01T17:11:35.429Z,1690909895.429 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2023-08-01T17:11:35.430Z,1690909895.430 [CommandExec](INFO): Initializing the command executive.
2023-08-01T17:11:35.431Z,1690909895.431 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2023-08-01T17:11:35.433Z,1690909895.433 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2023-08-01T17:11:35.434Z,1690909895.434 [controlThread](DEBUG): Initializing ControlThread
2023-08-01T17:11:35.435Z,1690909895.435 [InternalEnvSim](DEBUG):  InternaEnvlSim initializing...
2023-08-01T17:11:35.438Z,1690909895.438 [logger ThreadHandler](INFO): Handler Thread ID is 932
2023-08-01T17:11:35.469Z,1690909895.469 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 933
2023-08-01T17:11:35.470Z,1690909895.470 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-01T17:11:35.474Z,1690909895.474 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 935
2023-08-01T17:11:35.488Z,1690909895.488 [WetLabsBB2FL](INFO): Powering up
2023-08-01T17:11:35.489Z,1690909895.489 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 937
2023-08-01T17:11:35.512Z,1690909895.512 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 938
2023-08-01T17:11:35.528Z,1690909895.528 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939
2023-08-01T17:11:35.529Z,1690909895.529 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-01T17:11:35.548Z,1690909895.548 [Onboard ThreadHandler](INFO): Handler Thread ID is 940
2023-08-01T17:11:35.577Z,1690909895.577 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941
2023-08-01T17:11:35.586Z,1690909895.586 [SBIT](INFO): Initialize SBIT Component.
2023-08-01T17:11:35.587Z,1690909895.587 [SBIT](IMPORTANT): git: 2023-07-13-15-g9c64bfe99
2023-08-01T17:11:35.587Z,1690909895.587 [SBIT](INFO): git hash: 9c64bfe9970f1ae8576237371a5b1bbc175b54ef
2023-08-01T17:11:35.587Z,1690909895.587 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-01T17:11:35.589Z,1690909895.589 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
2023-08-01T17:11:35.591Z,1690909895.591 [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-01T17:11:35.600Z,1690909895.600 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-08-01T17:11:35.600Z,1690909895.600 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-08-01T17:11:35.600Z,1690909895.600 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-08-01T17:11:35.601Z,1690909895.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-08-01T17:11:35.601Z,1690909895.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-08-01T17:11:35.601Z,1690909895.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-08-01T17:11:35.601Z,1690909895.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-08-01T17:11:35.601Z,1690909895.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-08-01T17:11:35.601Z,1690909895.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-08-01T17:11:35.601Z,1690909895.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-08-01T17:11:35.602Z,1690909895.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-08-01T17:11:35.602Z,1690909895.602 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-08-01T17:11:35.602Z,1690909895.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-08-01T17:11:35.602Z,1690909895.602 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-08-01T17:11:35.602Z,1690909895.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-08-01T17:11:35.602Z,1690909895.602 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-08-01T17:11:35.603Z,1690909895.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-08-01T17:11:35.603Z,1690909895.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-08-01T17:11:35.603Z,1690909895.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-08-01T17:11:35.603Z,1690909895.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-08-01T17:11:35.603Z,1690909895.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-08-01T17:11:35.603Z,1690909895.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-08-01T17:11:35.605Z,1690909895.605 [SBIT](INFO): Beginning SBIT in 54.000000 seconds.
2023-08-01T17:11:35.610Z,1690909895.610 [IBIT](INFO): Initialize IBIT Component.
2023-08-01T17:11:35.616Z,1690909895.616 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-01T17:11:35.628Z,1690909895.628 [CBIT](DEBUG): Initialized mux pins.
2023-08-01T17:11:35.628Z,1690909895.628 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-01T17:11:35.652Z,1690909895.652 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-01T17:11:35.652Z,1690909895.652 [CBIT](DEBUG): Initializing heartbeat.
2023-08-01T17:11:35.724Z,1690909895.724 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-01T17:11:35.724Z,1690909895.724 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-01T17:11:35.764Z,1690909895.764 [CBIT](DEBUG): Backplane powered.
2023-08-01T17:11:35.768Z,1690909895.768 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-01T17:11:35.769Z,1690909895.769 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-01T17:11:35.769Z,1690909895.769 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-01T17:11:35.769Z,1690909895.769 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-01T17:11:35.770Z,1690909895.770 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-01T17:11:35.770Z,1690909895.770 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-08-01T17:11:35.771Z,1690909895.771 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-01T17:11:35.772Z,1690909895.772 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-01T17:11:35.772Z,1690909895.772 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-01T17:11:35.779Z,1690909895.779 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-01T17:11:35.781Z,1690909895.781 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-01T17:11:35.782Z,1690909895.782 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-01T17:11:35.783Z,1690909895.783 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-01T17:11:35.788Z,1690909895.788 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-01T17:11:35.856Z,1690909895.856 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-01T17:11:35.904Z,1690909895.904 [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-01T17:11:35.905Z,1690909895.905 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-01T17:11:35.962Z,1690909895.962 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-01T17:11:35.980Z,1690909895.980 [Radio_Surface](INFO): Powering up
2023-08-01T17:11:35.981Z,1690909895.981 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-01T17:11:35.983Z,1690909895.983 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-01T17:11:36.031Z,1690909896.031 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-01T17:11:36.042Z,1690909896.042 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-01T17:11:36.068Z,1690909896.068 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-01T17:11:36.071Z,1690909896.071 [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-01T17:11:36.076Z,1690909896.076 [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-01T17:11:36.100Z,1690909896.100 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-01T17:11:36.245Z,1690909896.245 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.894409 decibar, 0.000000 m
2023-08-01T17:11:36.253Z,1690909896.253 [Power24vConverter](INFO): Powering up.
2023-08-01T17:11:36.254Z,1690909896.254 [Sonardyne_Nano](INFO): Initializing.
2023-08-01T17:11:36.280Z,1690909896.280 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-08-01T17:11:36.339Z,1690909896.339 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-01T17:11:36.344Z,1690909896.344 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-01T17:11:36.345Z,1690909896.345 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-01T17:11:36.352Z,1690909896.352 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-01T17:11:36.353Z,1690909896.353 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-01T17:11:36.360Z,1690909896.360 [MassServo](DEBUG): Initializing MassServo.
2023-08-01T17:11:36.361Z,1690909896.361 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-01T17:11:36.368Z,1690909896.368 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-01T17:11:36.369Z,1690909896.369 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-01T17:11:36.376Z,1690909896.376 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-01T17:11:36.612Z,1690909896.612 [DVL_micro](INFO): Powering down
2023-08-01T17:11:37.588Z,1690909897.588 [WetLabsBB2FL](INFO): Powering down
2023-08-01T17:11:38.511Z,1690909898.511 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-08-01T17:11:39.753Z,1690909899.753 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2023-08-01T17:11:41.989Z,1690909901.989 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-01T17:11:42.500Z,1690909902.500 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-08-01T17:12:04.782Z,1690909924.782 [NAL9602](INFO): Powering up NAL9602
2023-08-01T17:12:15.688Z,1690909935.688 [NAL9602](INFO): NAL9602 initialized
2023-08-01T17:12:30.245Z,1690909950.245 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-01T17:12:30.249Z,1690909950.249 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-01T17:12:41.186Z,1690909961.186 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005759
CHAN A1 (24V): 0.002167
CHAN A2 (12V): -0.005594
CHAN A3 (5V): -0.002949
CHAN B0 (3.3V): -0.001493
CHAN B1 (3.15aV): -0.001249
CHAN B2 (3.15bV): -0.001284
CHAN B3 (GND): -0.000925
OPEN: 0.000351
Full Scale: +/- 1 mA
2023-08-01T17:13:24.398Z,1690910004.398 [SBIT](IMPORTANT): SBIT PASSED
2023-08-01T17:13:24.398Z,1690910004.398 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-08-01T17:13:24.399Z,1690910004.399 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-08-01T17:13:24.399Z,1690910004.399 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;

2023-08-01T17:13:24.399Z,1690910004.399 [SBIT](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup=0 bool;

2023-08-01T17:13:24.399Z,1690910004.399 [SBIT](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup=1 bool;

2023-08-01T17:13:24.399Z,1690910004.399 [SBIT](IMPORTANT): Depth_Keller.offset=-0.35 decibar;

2023-08-01T17:13:24.400Z,1690910004.400 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-08-01T17:13:24.400Z,1690910004.400 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;

2023-08-01T17:13:24.400Z,1690910004.400 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2023-08-01T17:13:24.400Z,1690910004.400 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-08-01T17:13:24.400Z,1690910004.400 [SBIT](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 1.000000 kilogram_per_cubic_meter;

2023-08-01T17:13:24.400Z,1690910004.400 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234.769539 cubic_centimeter;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): VerticalControl.massDefault=8.539099 millimeter;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-08-01T17:13:24.401Z,1690910004.401 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-08-01T17:13:24.402Z,1690910004.402 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=0 bool;

2023-08-01T17:13:24.782Z,1690910004.782 [MissionManager](IMPORTANT): Started mission Startup
2023-08-01T17:13:24.783Z,1690910004.783 [Startup] Running Loop=1
2023-08-01T17:13:24.783Z,1690910004.783 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-01T17:13:24.783Z,1690910004.783 [Startup:A.GoToSurface] Running Loop=1
2023-08-01T17:13:24.783Z,1690910004.783 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-01T17:13:24.783Z,1690910004.783 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-01T17:13:24.784Z,1690910004.784 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-01T17:13:24.784Z,1690910004.784 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-01T17:13:24.785Z,1690910004.785 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-01T17:13:24.785Z,1690910004.785 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-01T17:13:24.786Z,1690910004.786 [Startup:StartupSatComms] Running Loop=1
2023-08-01T17:13:24.787Z,1690910004.787 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-01T17:13:24.787Z,1690910004.787 [Startup:StartupSatComms:A] Running Loop=1
2023-08-01T17:13:25.182Z,1690910005.182 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-01T17:13:32.448Z,1690910012.448 [NAL9602](INFO): SBD MO Status=2, MOMSN=7069, MT Status=2, MTMSN=0
2023-08-01T17:13:32.449Z,1690910012.449 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:13:45.402Z,1690910025.402 [NAL9602](INFO): SBD MO Status=2, MOMSN=7069, MT Status=2, MTMSN=0
2023-08-01T17:13:45.402Z,1690910025.402 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:14:19.717Z,1690910059.717 [NAL9602](INFO): SBD MO Status=2, MOMSN=7069, MT Status=2, MTMSN=0
2023-08-01T17:14:19.717Z,1690910059.717 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:14:24.975Z,1690910064.975 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-01T17:13:24.8Z
2023-08-01T17:14:24.975Z,1690910064.975 [Startup:StartupSatComms:A] Stopped
2023-08-01T17:14:24.975Z,1690910064.975 [Startup:StartupSatComms:B] Running Loop=1
2023-08-01T17:14:25.382Z,1690910065.382 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-01T17:14:35.894Z,1690910075.894 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-08-01T17:14:35.894Z,1690910075.894 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-01T17:14:35.906Z,1690910075.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-01T17:14:36.311Z,1690910076.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-01T17:14:36.311Z,1690910076.311 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-08-01T17:14:36.689Z,1690910076.689 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-01T17:14:45.985Z,1690910085.985 [DVL_micro](ERROR): only read 2 of 4 data items
2023-08-01T17:14:45.985Z,1690910085.985 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+99999.99
2023-08-01T17:14:48.805Z,1690910088.805 [NAL9602](INFO): SBD MO Status=0, MOMSN=7069, MT Status=0, MTMSN=0
2023-08-01T17:14:48.805Z,1690910088.805 [NAL9602](INFO): No messages in MT queue
2023-08-01T17:15:14.535Z,1690910114.535 [NAL9602](INFO): SBD MO Status=2, MOMSN=7070, MT Status=2, MTMSN=0
2023-08-01T17:15:14.535Z,1690910114.535 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:15:17.714Z,1690910117.714 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004345
2023-08-01T17:15:24.984Z,1690910124.984 [NAL9602](INFO): SBD MO Status=1, MOMSN=7070, MT Status=0, MTMSN=0
2023-08-01T17:15:25.037Z,1690910125.037 [NAL9602](INFO): Sent 40 bytes from file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:15:25.037Z,1690910125.037 [NAL9602](INFO): Packets left to send: 0
2023-08-01T17:15:25.098Z,1690910125.098 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-01T17:14:24.0Z
2023-08-01T17:15:25.098Z,1690910125.098 [Startup:StartupSatComms:B] Stopped
2023-08-01T17:15:25.098Z,1690910125.098 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-01T17:15:25.098Z,1690910125.098 [Startup:StartupSatComms] Stopped
2023-08-01T17:15:25.098Z,1690910125.098 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-01T17:15:25.099Z,1690910125.099 [Startup](INFO): Completed Startup
2023-08-01T17:15:25.099Z,1690910125.099 [MissionManager](INFO): Startup is completed.
2023-08-01T17:15:25.099Z,1690910125.099 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-01T17:15:25.099Z,1690910125.099 [Startup] Stopped
2023-08-01T17:15:25.100Z,1690910125.100 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-01T17:15:25.128Z,1690910125.128 [Startup:A.GoToSurface] Stopped
2023-08-01T17:15:25.128Z,1690910125.128 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-01T17:15:25.393Z,1690910125.393 [MissionManager](IMPORTANT): Started mission Default
2023-08-01T17:15:25.393Z,1690910125.393 [Default] Running Loop=1
2023-08-01T17:15:25.393Z,1690910125.393 [Default](DEBUG): Aggregate::initialize Default
2023-08-01T17:15:25.393Z,1690910125.393 [Default:B.GoToSurface] Running Loop=1
2023-08-01T17:15:25.394Z,1690910125.394 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-01T17:15:25.394Z,1690910125.394 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-01T17:15:25.394Z,1690910125.394 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-01T17:15:25.394Z,1690910125.394 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-01T17:15:25.395Z,1690910125.395 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-01T17:15:25.395Z,1690910125.395 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-01T17:15:25.395Z,1690910125.395 [Default:A.Wait] Running Loop=1
2023-08-01T17:15:25.395Z,1690910125.395 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:15:25.865Z,1690910125.865 [DVL_micro](INFO): Powering down
2023-08-01T17:15:27.790Z,1690910127.790 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:15:27.790Z,1690910127.790 [DataOverHttps](FAULT): Could not open file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:15:36.018Z,1690910136.018 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:15:36.018Z,1690910136.018 [DataOverHttps](FAULT): Could not open file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:15:38.719Z,1690910138.719 [Default:A.Wait](INFO): Done Waiting.
2023-08-01T17:15:38.719Z,1690910138.719 [Default:A.Wait] Stopped
2023-08-01T17:15:38.720Z,1690910138.720 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:15:39.086Z,1690910139.086 [Default:CheckIn] Running Loop=1
2023-08-01T17:15:39.086Z,1690910139.086 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:15:39.086Z,1690910139.086 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:15:39.501Z,1690910139.501 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-01T17:16:06.630Z,1690910166.630 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:16:06.630Z,1690910166.630 [DataOverHttps](FAULT): Could not open file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:16:37.018Z,1690910197.018 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:16:37.019Z,1690910197.019 [DataOverHttps](FAULT): Could not open file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:17:09.310Z,1690910229.310 [DataOverHttps](IMPORTANT): Could not stat file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:17:09.310Z,1690910229.310 [DataOverHttps](FAULT): Could not open file Logs/20230801T163112/Courier0022.lzma
2023-08-01T17:17:30.586Z,1690910250.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171706.00,A,4313.61797,N,08620.33137,W,0.603,266.98,010823,,,A*76
2023-08-01T17:17:30.589Z,1690910250.589 [NAL9602](INFO): GPS fix at 20230801T171706: (43.226966, -86.338856)
2023-08-01T17:17:30.599Z,1690910250.599 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:17:30.599Z,1690910250.599 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:17:31.037Z,1690910251.037 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-01T17:17:38.031Z,1690910258.031 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230801T171122/Courier0000.lzma
2023-08-01T17:17:39.034Z,1690910259.034 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0000.lzma.bak
2023-08-01T17:17:39.034Z,1690910259.034 [DataOverHttps](INFO): SBD MOMSN=18505236
2023-08-01T17:17:58.160Z,1690910278.160 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230801T171122/Courier0004.lzma
2023-08-01T17:17:59.162Z,1690910279.162 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0004.lzma.bak
2023-08-01T17:17:59.162Z,1690910279.162 [DataOverHttps](INFO): SBD MOMSN=18505241
2023-08-01T17:18:02.761Z,1690910282.761 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:18:18.929Z,1690910298.929 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20230801T163112/Express0023.lzma
2023-08-01T17:18:19.930Z,1690910299.930 [DataOverHttps](INFO): Moved sent file to Logs/20230801T163112/Express0023.lzma.bak
2023-08-01T17:18:19.930Z,1690910299.930 [DataOverHttps](INFO): SBD MOMSN=18505244
2023-08-01T17:18:35.741Z,1690910315.741 [DataOverHttps](INFO): Sending 1114 bytes from file Logs/20230801T171122/Express0001.lzma
2023-08-01T17:18:36.742Z,1690910316.742 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0001.lzma.bak
2023-08-01T17:18:36.742Z,1690910316.742 [DataOverHttps](INFO): SBD MOMSN=18505247
2023-08-01T17:18:55.586Z,1690910335.586 [DataOverHttps](INFO): Sending 354 bytes from file Logs/20230801T171122/Express0005.lzma
2023-08-01T17:18:56.574Z,1690910336.574 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0005.lzma.bak
2023-08-01T17:18:56.574Z,1690910336.574 [DataOverHttps](INFO): SBD MOMSN=18505279
2023-08-01T17:18:59.270Z,1690910339.270 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:18:59.270Z,1690910339.270 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:18:59.271Z,1690910339.271 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:19:41.330Z,1690910381.330 [DVL_micro](ERROR): only read 0 of 4 data items
2023-08-01T17:19:41.330Z,1690910381.330 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999.99,+9999.99,+9999.99
2023-08-01T17:19:58.634Z,1690910398.634 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:19:58.634Z,1690910398.634 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.3,0000.0,148

2023-08-01T17:20:19.651Z,1690910419.651 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-01T17:20:19.654Z,1690910419.654 [BPC1](INFO): Received data from all battery sticks.
2023-08-01T17:23:26.109Z,1690910606.109 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2023-08-01T17:23:26.110Z,1690910606.110 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 96.000000 %
2023-08-01T17:23:34.651Z,1690910614.651 [CommandLine](INFO): End of History
2023-08-01T17:23:35.764Z,1690910615.764 [CommandLine](INFO): End of History
2023-08-01T17:23:36.872Z,1690910616.872 [CommandLine](INFO): End of History
2023-08-01T17:23:37.898Z,1690910617.898 [CommandLine](INFO): End of History
2023-08-01T17:23:38.988Z,1690910618.988 [CommandLine](INFO): End of History
2023-08-01T17:23:39.997Z,1690910619.997 [CommandLine](INFO): End of History
2023-08-01T17:23:41.121Z,1690910621.121 [CommandLine](INFO): End of History
2023-08-01T17:23:42.229Z,1690910622.229 [CommandLine](INFO): End of History
2023-08-01T17:23:43.337Z,1690910623.337 [CommandLine](INFO): End of History
2023-08-01T17:23:44.350Z,1690910624.350 [CommandLine](INFO): End of History
2023-08-01T17:23:45.460Z,1690910625.460 [CommandLine](INFO): End of History
2023-08-01T17:23:46.574Z,1690910626.574 [CommandLine](INFO): End of History
2023-08-01T17:23:47.587Z,1690910627.587 [CommandLine](INFO): End of History
2023-08-01T17:23:49.209Z,1690910629.209 [CommandLine](INFO): End of History
2023-08-01T17:23:51.455Z,1690910631.455 [CommandLine](INFO): End of History
2023-08-01T17:23:53.981Z,1690910633.981 [CommandLine](INFO): End of History
2023-08-01T17:23:57.303Z,1690910637.303 [CommandLine](INFO): End of History
2023-08-01T17:23:59.332Z,1690910639.332 [CommandLine](INFO): End of History
2023-08-01T17:23:59.963Z,1690910639.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:23:59.968Z,1690910639.968 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:23:59.968Z,1690910639.968 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:23:59.968Z,1690910639.968 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:24:00.379Z,1690910640.379 [Default:CheckIn:D] Stopped
2023-08-01T17:24:00.379Z,1690910640.379 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:24:00.824Z,1690910640.824 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.583089 min 
2023-08-01T17:24:00.824Z,1690910640.824 [Default:CheckIn:E] Stopped
2023-08-01T17:24:00.825Z,1690910640.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:24:00.825Z,1690910640.825 [Default:CheckIn] Stopped
2023-08-01T17:24:00.825Z,1690910640.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:24:00.825Z,1690910640.825 [Default:CheckIn](INFO): Running loop #2
2023-08-01T17:24:00.825Z,1690910640.825 [Default:CheckIn] Running Loop=2
2023-08-01T17:24:00.825Z,1690910640.825 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:24:00.825Z,1690910640.825 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:24:01.473Z,1690910641.473 [CommandLine](INFO): End of History
2023-08-01T17:24:02.788Z,1690910642.788 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172341.00,A,4313.61860,N,08620.33707,W,1.186,278.07,010823,,,A*72
2023-08-01T17:24:02.791Z,1690910642.791 [NAL9602](INFO): GPS fix at 20230801T172341: (43.226977, -86.338951)
2023-08-01T17:24:02.887Z,1690910642.887 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:24:02.887Z,1690910642.887 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:24:03.754Z,1690910643.754 [CommandLine](INFO): End of History
2023-08-01T17:24:06.639Z,1690910646.639 [CommandLine](INFO): End of History
2023-08-01T17:24:09.961Z,1690910649.961 [CommandLine](INFO): End of History
2023-08-01T17:24:13.098Z,1690910653.098 [CommandLine](INFO): End of History
2023-08-01T17:24:15.516Z,1690910655.516 [CommandLine](INFO): End of History
2023-08-01T17:24:17.182Z,1690910657.182 [CommandLine](INFO): End of History
2023-08-01T17:24:17.719Z,1690910657.719 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230801T171122/Courier0007.lzma
2023-08-01T17:24:18.257Z,1690910658.257 [CommandLine](INFO): End of History
2023-08-01T17:24:18.722Z,1690910658.722 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0007.lzma.bak
2023-08-01T17:24:18.722Z,1690910658.722 [DataOverHttps](INFO): SBD MOMSN=18505301
2023-08-01T17:24:19.401Z,1690910659.401 [CommandLine](INFO): End of History
2023-08-01T17:24:21.345Z,1690910661.345 [CommandLine](INFO): End of History
2023-08-01T17:24:22.502Z,1690910662.502 [CommandLine](INFO): End of History
2023-08-01T17:24:23.693Z,1690910663.693 [CommandLine](INFO): End of History
2023-08-01T17:24:25.857Z,1690910665.857 [CommandLine](INFO): End of History
2023-08-01T17:24:28.071Z,1690910668.071 [CommandLine](INFO): End of History
2023-08-01T17:24:30.198Z,1690910670.198 [CommandLine](INFO): End of History
2023-08-01T17:24:31.509Z,1690910671.509 [CommandLine](INFO): End of History
2023-08-01T17:24:32.815Z,1690910672.815 [CommandLine](INFO): End of History
2023-08-01T17:24:32.818Z,1690910672.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=7071, MT Status=2, MTMSN=0
2023-08-01T17:24:32.818Z,1690910672.818 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:24:33.903Z,1690910673.903 [CommandLine](INFO): End of History
2023-08-01T17:24:36.214Z,1690910676.214 [CommandLine](INFO): End of History
2023-08-01T17:24:38.368Z,1690910678.368 [CommandLine](INFO): End of History
2023-08-01T17:24:40.719Z,1690910680.719 [CommandLine](INFO): End of History
2023-08-01T17:24:42.091Z,1690910682.091 [NAL9602](INFO): SBD MO Status=0, MOMSN=7071, MT Status=0, MTMSN=0
2023-08-01T17:24:42.091Z,1690910682.091 [NAL9602](INFO): No messages in MT queue
2023-08-01T17:24:42.842Z,1690910682.842 [CommandLine](INFO): End of History
2023-08-01T17:24:44.021Z,1690910684.021 [CommandLine](INFO): End of History
2023-08-01T17:24:44.040Z,1690910684.040 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20230801T171122/Express0008.lzma
2023-08-01T17:24:45.042Z,1690910685.042 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0008.lzma.bak
2023-08-01T17:24:45.042Z,1690910685.042 [DataOverHttps](INFO): SBD MOMSN=18505310
2023-08-01T17:24:46.948Z,1690910686.948 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:24:46.948Z,1690910686.948 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:24:46.948Z,1690910686.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:25:12.791Z,1690910712.791 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:27:37.464Z,1690910857.464 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:27:37.464Z,1690910857.464 [DVL_micro](ERROR): Failed to parse:
:BI,+01376,-0073,+00000,I
2023-08-01T17:29:47.664Z,1690910987.664 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:29:47.664Z,1690910987.664 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:29:47.664Z,1690910987.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:29:47.665Z,1690910987.665 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:29:48.080Z,1690910988.080 [Default:CheckIn:D] Stopped
2023-08-01T17:29:48.080Z,1690910988.080 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:29:48.476Z,1690910988.476 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.378116 min 
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn:E] Stopped
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn] Stopped
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn](INFO): Running loop #3
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn] Running Loop=3
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:29:48.477Z,1690910988.477 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:29:50.482Z,1690910990.482 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172929.00,A,4313.61535,N,08620.33952,W,0.117,278.07,010823,,,A*7C
2023-08-01T17:29:50.484Z,1690910990.484 [NAL9602](INFO): GPS fix at 20230801T172929: (43.226923, -86.338992)
2023-08-01T17:29:50.495Z,1690910990.495 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:29:50.495Z,1690910990.495 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:30:01.563Z,1690911001.563 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230801T171122/Courier0010.lzma
2023-08-01T17:30:02.566Z,1690911002.566 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0010.lzma.bak
2023-08-01T17:30:02.566Z,1690911002.566 [DataOverHttps](INFO): SBD MOMSN=18505348
2023-08-01T17:30:20.499Z,1690911020.499 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230801T171122/Express0011.lzma
2023-08-01T17:30:21.502Z,1690911021.502 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0011.lzma.bak
2023-08-01T17:30:21.502Z,1690911021.502 [DataOverHttps](INFO): SBD MOMSN=18505351
2023-08-01T17:30:23.727Z,1690911023.727 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:30:23.727Z,1690911023.727 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:30:23.728Z,1690911023.728 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:31:16.629Z,1690911076.629 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:31:16.629Z,1690911076.629 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+260,1489.0,000
2023-08-01T17:32:30.114Z,1690911150.114 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:32:30.114Z,1690911150.114 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,00.0,1489.0,000
2023-08-01T17:33:07.705Z,1690911187.705 [DVL_micro](ERROR): Failed to parse:
:SA,-02.69,+03.95,326.0
2023-08-01T17:33:15.378Z,1690911195.378 [NAL9602](INFO): SBD MO Status=0, MOMSN=7072, MT Status=0, MTMSN=0
2023-08-01T17:33:15.378Z,1690911195.378 [NAL9602](INFO): No messages in MT queue
2023-08-01T17:33:46.065Z,1690911226.065 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:34:23.649Z,1690911263.649 [DVL_micro](ERROR): only read 1 of 4 data items
2023-08-01T17:34:23.649Z,1690911263.649 [DVL_micro](ERROR): Failed to parse:
:RD,+9999..99,+9999.99,+9999.99
2023-08-01T17:35:16.568Z,1690911316.568 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:35:16.568Z,1690911316.568 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.6,0000.0,10
2023-08-01T17:35:24.251Z,1690911324.251 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:35:24.251Z,1690911324.251 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:35:24.251Z,1690911324.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:35:24.251Z,1690911324.251 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:35:24.659Z,1690911324.659 [Default:CheckIn:D] Stopped
2023-08-01T17:35:24.659Z,1690911324.659 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:35:25.060Z,1690911325.060 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.987764 min 
2023-08-01T17:35:25.060Z,1690911325.060 [Default:CheckIn:E] Stopped
2023-08-01T17:35:25.060Z,1690911325.060 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:35:25.060Z,1690911325.060 [Default:CheckIn] Stopped
2023-08-01T17:35:25.060Z,1690911325.060 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:35:25.060Z,1690911325.060 [Default:CheckIn](INFO): Running loop #4
2023-08-01T17:35:25.060Z,1690911325.060 [Default:CheckIn] Running Loop=4
2023-08-01T17:35:25.061Z,1690911325.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:35:25.061Z,1690911325.061 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:35:27.066Z,1690911327.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173505.00,A,4313.61201,N,08620.33436,W,0.039,0.00,010823,,,A*77
2023-08-01T17:35:27.068Z,1690911327.068 [NAL9602](INFO): GPS fix at 20230801T173505: (43.226867, -86.338906)
2023-08-01T17:35:27.098Z,1690911327.098 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:35:27.099Z,1690911327.099 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:35:34.851Z,1690911334.851 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230801T171122/Courier0013.lzma
2023-08-01T17:35:35.854Z,1690911335.854 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0013.lzma.bak
2023-08-01T17:35:35.854Z,1690911335.854 [DataOverHttps](INFO): SBD MOMSN=18505441
2023-08-01T17:35:51.735Z,1690911351.735 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230801T171122/Express0014.lzma
2023-08-01T17:35:52.738Z,1690911352.738 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0014.lzma.bak
2023-08-01T17:35:52.738Z,1690911352.738 [DataOverHttps](INFO): SBD MOMSN=18505445
2023-08-01T17:35:55.437Z,1690911355.437 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:35:55.437Z,1690911355.437 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:35:55.437Z,1690911355.437 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:40:06.313Z,1690911606.313 [NAL9602](INFO): SBD MO Status=2, MOMSN=7073, MT Status=2, MTMSN=0
2023-08-01T17:40:06.313Z,1690911606.313 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:40:15.999Z,1690911615.999 [NAL9602](INFO): SBD MO Status=0, MOMSN=7073, MT Status=0, MTMSN=0
2023-08-01T17:40:15.999Z,1690911615.999 [NAL9602](INFO): No messages in MT queue
2023-08-01T17:40:24.489Z,1690911624.489 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:40:24.489Z,1690911624.489 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35,0000.0,1489.0,000
2023-08-01T17:40:26.918Z,1690911626.918 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:40:26.918Z,1690911626.918 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.6,489.0,000
2023-08-01T17:40:46.721Z,1690911646.721 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:40:55.845Z,1690911655.845 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:40:55.846Z,1690911655.846 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:40:55.846Z,1690911655.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:40:55.846Z,1690911655.846 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:40:56.233Z,1690911656.233 [Default:CheckIn:D] Stopped
2023-08-01T17:40:56.233Z,1690911656.233 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:40:56.674Z,1690911656.674 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.513989 min 
2023-08-01T17:40:56.674Z,1690911656.674 [Default:CheckIn:E] Stopped
2023-08-01T17:40:56.674Z,1690911656.674 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:40:56.674Z,1690911656.674 [Default:CheckIn] Stopped
2023-08-01T17:40:56.675Z,1690911656.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:40:56.675Z,1690911656.675 [Default:CheckIn](INFO): Running loop #5
2023-08-01T17:40:56.675Z,1690911656.675 [Default:CheckIn] Running Loop=5
2023-08-01T17:40:56.675Z,1690911656.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:40:56.675Z,1690911656.675 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:40:57.141Z,1690911657.141 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:40:57.142Z,1690911657.142 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,00.0,1489.0,000
2023-08-01T17:40:58.615Z,1690911658.615 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174037.00,A,4313.61368,N,08620.34149,W,0.233,0.00,010823,,,A*78
2023-08-01T17:40:58.635Z,1690911658.635 [NAL9602](INFO): GPS fix at 20230801T174037: (43.226895, -86.339025)
2023-08-01T17:40:58.707Z,1690911658.707 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:40:58.707Z,1690911658.707 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:41:04.753Z,1690911664.753 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:41:04.754Z,1690911664.754 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000006.7,0000.0,1489.0,000
2023-08-01T17:41:17.475Z,1690911677.475 [DVL_micro](ERROR): Failed to parse:
:WI,+00999,-00134,+00210,+00000,A
2023-08-01T17:41:21.888Z,1690911681.888 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230801T171122/Courier0016.lzma
2023-08-01T17:41:22.325Z,1690911682.325 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:41:22.326Z,1690911682.326 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+260,1489.0,000
2023-08-01T17:41:22.890Z,1690911682.890 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0016.lzma.bak
2023-08-01T17:41:22.890Z,1690911682.890 [DataOverHttps](INFO): SBD MOMSN=18505455
2023-08-01T17:41:25.135Z,1690911685.135 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:41:25.135Z,1690911685.135 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.7,000.0,000
2023-08-01T17:41:27.119Z,1690911687.119 [NAL9602](INFO): SBD MO Status=2, MOMSN=7074, MT Status=2, MTMSN=0
2023-08-01T17:41:27.119Z,1690911687.119 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:41:29.990Z,1690911689.990 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:41:29.991Z,1690911689.991 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.7,0000.0000
2023-08-01T17:41:37.683Z,1690911697.683 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:41:37.696Z,1690911697.696 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+0.0,1489.0,000
2023-08-01T17:41:42.540Z,1690911702.540 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:41:42.540Z,1690911702.540 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+26.7,0000.0,1489.0,000
2023-08-01T17:41:45.345Z,1690911705.345 [DVL_micro](ERROR): Failed to parse:
:T00000000,35.0,+26.7,0000.0,1489.0,000
2023-08-01T17:42:03.427Z,1690911723.427 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230801T171122/Express0017.lzma
2023-08-01T17:42:04.426Z,1690911724.426 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0017.lzma.bak
2023-08-01T17:42:04.427Z,1690911724.427 [DataOverHttps](INFO): SBD MOMSN=18505458
2023-08-01T17:42:09.770Z,1690911729.770 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:42:09.770Z,1690911729.770 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:42:09.770Z,1690911729.770 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:42:15.411Z,1690911735.411 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:42:15.411Z,1690911735.411 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2023-08-01T17:42:25.851Z,1690911745.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=7074, MT Status=2, MTMSN=0
2023-08-01T17:42:25.851Z,1690911745.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:42:30.724Z,1690911750.724 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:42:30.724Z,1690911750.724 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35,0000.0,1489.0,000
2023-08-01T17:42:47.659Z,1690911767.659 [NAL9602](INFO): SBD MO Status=2, MOMSN=7074, MT Status=2, MTMSN=0
2023-08-01T17:42:47.660Z,1690911767.660 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T17:42:54.654Z,1690911774.654 [NAL9602](INFO): SBD MO Status=0, MOMSN=7074, MT Status=0, MTMSN=0
2023-08-01T17:42:54.654Z,1690911774.654 [NAL9602](INFO): No messages in MT queue
2023-08-01T17:42:58.645Z,1690911778.645 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:42:58.645Z,1690911778.645 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.7,0000.0000
2023-08-01T17:43:03.535Z,1690911783.535 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:43:03.535Z,1690911783.535 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2023-08-01T17:43:21.314Z,1690911801.314 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:43:21.314Z,1690911801.314 [DVL_micro](ERROR): Failed to parse:
:BI,-0044,-00061,+00000,I
2023-08-01T17:43:25.349Z,1690911805.349 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:44:14.337Z,1690911854.337 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:44:14.337Z,1690911854.337 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.7,0000.0,9.99
2023-08-01T17:44:36.967Z,1690911876.967 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:44:36.967Z,1690911876.967 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+26.7,0000.0,1489.0,000
2023-08-01T17:44:54.795Z,1690911894.795 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:44:54.795Z,1690911894.795 [DVL_micro](ERROR): Failed to parse:
:TS,000000005.0,+26.8,0000.0,1489.0,000
2023-08-01T17:45:27.591Z,1690911927.591 [DVL_micro](ERROR): only read 2 of 4 data items
2023-08-01T17:45:27.592Z,1690911927.592 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,,+9999.99
2023-08-01T17:45:29.920Z,1690911929.920 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:45:29.920Z,1690911929.920 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+26.8,0000.0,14
2023-08-01T17:46:15.581Z,1690911975.581 [DVL_micro](ERROR): Failed to parse:
:SA,-02.71,+03.94,326.2
2023-08-01T17:47:10.527Z,1690912030.527 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:47:10.528Z,1690912030.528 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:47:10.528Z,1690912030.528 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:47:10.528Z,1690912030.528 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:47:10.951Z,1690912030.951 [Default:CheckIn:D] Stopped
2023-08-01T17:47:10.951Z,1690912030.951 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:47:11.352Z,1690912031.352 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.759294 min 
2023-08-01T17:47:11.352Z,1690912031.352 [Default:CheckIn:E] Stopped
2023-08-01T17:47:11.352Z,1690912031.352 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:47:11.353Z,1690912031.353 [Default:CheckIn] Stopped
2023-08-01T17:47:11.353Z,1690912031.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:47:11.353Z,1690912031.353 [Default:CheckIn](INFO): Running loop #6
2023-08-01T17:47:11.353Z,1690912031.353 [Default:CheckIn] Running Loop=6
2023-08-01T17:47:11.353Z,1690912031.353 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:47:11.353Z,1690912031.353 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:47:13.354Z,1690912033.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174651.00,A,4313.61480,N,08620.34055,W,0.330,335.13,010823,,,A*76
2023-08-01T17:47:13.357Z,1690912033.357 [NAL9602](INFO): GPS fix at 20230801T174651: (43.226913, -86.339009)
2023-08-01T17:47:13.389Z,1690912033.389 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:47:13.390Z,1690912033.390 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:47:20.703Z,1690912040.703 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230801T171122/Courier0019.lzma
2023-08-01T17:47:21.706Z,1690912041.706 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0019.lzma.bak
2023-08-01T17:47:21.706Z,1690912041.706 [DataOverHttps](INFO): SBD MOMSN=18505477
2023-08-01T17:47:38.767Z,1690912058.767 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230801T171122/Express0020.lzma
2023-08-01T17:47:39.770Z,1690912059.770 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0020.lzma.bak
2023-08-01T17:47:39.770Z,1690912059.770 [DataOverHttps](INFO): SBD MOMSN=18505480
2023-08-01T17:47:42.072Z,1690912062.072 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:47:42.072Z,1690912062.072 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:47:42.073Z,1690912062.073 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:48:39.445Z,1690912119.445 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:48:39.445Z,1690912119.445 [DVL_micro](ERROR): Failed to parse:
:BI,+0050,+00497,+00000,I
2023-08-01T17:49:57.591Z,1690912197.591 [DVL_micro](ERROR): Failed to parse:
:WI,+01155,-00018,+00216,+00000,A
2023-08-01T17:50:05.228Z,1690912205.228 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:50:05.236Z,1690912205.236 [DVL_micro](ERROR): Failed to parse:
:BI,-00369,-0030,+00000,I
2023-08-01T17:51:56.361Z,1690912316.361 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:51:56.361Z,1690912316.361 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2023-08-01T17:52:15.820Z,1690912335.820 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-01T17:52:42.515Z,1690912362.515 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:52:42.515Z,1690912362.515 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:52:42.515Z,1690912362.515 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:52:42.515Z,1690912362.515 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:52:42.902Z,1690912362.902 [Default:CheckIn:D] Stopped
2023-08-01T17:52:42.902Z,1690912362.902 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:52:43.307Z,1690912363.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.291817 min 
2023-08-01T17:52:43.307Z,1690912363.307 [Default:CheckIn:E] Stopped
2023-08-01T17:52:43.308Z,1690912363.308 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:52:43.309Z,1690912363.309 [Default:CheckIn] Stopped
2023-08-01T17:52:43.309Z,1690912363.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:52:43.309Z,1690912363.309 [Default:CheckIn](INFO): Running loop #7
2023-08-01T17:52:43.309Z,1690912363.309 [Default:CheckIn] Running Loop=7
2023-08-01T17:52:43.309Z,1690912363.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:52:43.309Z,1690912363.309 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:52:45.326Z,1690912365.326 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175223.00,A,4313.61644,N,08620.33879,W,0.641,335.13,010823,,,A*7E
2023-08-01T17:52:45.328Z,1690912365.328 [NAL9602](INFO): GPS fix at 20230801T175223: (43.226941, -86.338980)
2023-08-01T17:52:45.339Z,1690912365.339 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:52:45.339Z,1690912365.339 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:52:53.035Z,1690912373.035 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230801T171122/Courier0022.lzma
2023-08-01T17:52:54.038Z,1690912374.038 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0022.lzma.bak
2023-08-01T17:52:54.038Z,1690912374.038 [DataOverHttps](INFO): SBD MOMSN=18505494
2023-08-01T17:53:14.556Z,1690912394.556 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20230801T171122/Express0023.lzma
2023-08-01T17:53:15.558Z,1690912395.558 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0023.lzma.bak
2023-08-01T17:53:15.558Z,1690912395.558 [DataOverHttps](INFO): SBD MOMSN=18505498
2023-08-01T17:53:17.728Z,1690912397.728 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:53:17.728Z,1690912397.728 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:53:17.728Z,1690912397.728 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:53:18.113Z,1690912398.113 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T17:53:47.594Z,1690912427.594 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-08-01T17:53:47.594Z,1690912427.594 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35,0000.0,1489.0,000
2023-08-01T17:55:13.333Z,1690912513.333 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T17:55:13.334Z,1690912513.334 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+

2023-08-01T17:55:43.614Z,1690912543.614 [DVL_micro](ERROR): No DVL communication! Re-initializing
2023-08-01T17:55:43.614Z,1690912543.614 [DVL_micro] Communications Fault, FailCount= 1
2023-08-01T17:55:43.614Z,1690912543.614 [DVL_micro](ERROR): Communications Fault
2023-08-01T17:55:43.634Z,1690912543.634 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-08-01T17:55:44.021Z,1690912544.021 [DVL_micro](INFO): Powering down
2023-08-01T17:55:44.424Z,1690912544.424 [Power24vConverter](INFO): Powering down.
2023-08-01T17:55:44.886Z,1690912544.886 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-08-01T17:55:44.886Z,1690912544.886 [DVL_micro] No Fault, FailCount= 1
2023-08-01T17:55:45.236Z,1690912545.236 [Power24vConverter](INFO): Powering up.
2023-08-01T17:56:02.641Z,1690912562.641 [DVL_micro](FAULT): Failed to initialize within timeout.
2023-08-01T17:56:02.641Z,1690912562.641 [DVL_micro] Communications Fault, FailCount= 2
2023-08-01T17:56:02.641Z,1690912562.641 [DVL_micro](ERROR): Communications Fault
2023-08-01T17:56:02.709Z,1690912562.709 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-08-01T17:56:03.016Z,1690912563.016 [DVL_micro](INFO): Powering down
2023-08-01T17:56:03.874Z,1690912563.874 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-08-01T17:56:03.874Z,1690912563.874 [DVL_micro] No Fault, FailCount= 2
2023-08-01T17:56:21.587Z,1690912581.587 [DVL_micro](FAULT): Failed to initialize within timeout.
2023-08-01T17:56:21.587Z,1690912581.587 [DVL_micro] Communications Fault, FailCount= 3
2023-08-01T17:56:21.587Z,1690912581.587 [DVL_micro](ERROR): Communications Fault
2023-08-01T17:56:21.636Z,1690912581.636 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-08-01T17:56:21.637Z,1690912581.637 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2023-08-01T17:56:21.991Z,1690912581.991 [DVL_micro](INFO): Powering down
2023-08-01T17:56:22.026Z,1690912582.026 [CommandExec](FAULT): Scheduling is paused
2023-08-01T17:56:22.026Z,1690912582.026 [CBIT](INFO): Critical error at 20230801T175621
2023-08-01T17:56:22.027Z,1690912582.027 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-08-01T17:58:18.362Z,1690912698.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T17:58:18.362Z,1690912698.362 [Default:CheckIn:C.Wait] Stopped
2023-08-01T17:58:18.362Z,1690912698.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T17:58:18.362Z,1690912698.362 [Default:CheckIn:D] Running Loop=1
2023-08-01T17:58:18.763Z,1690912698.763 [Default:CheckIn:D] Stopped
2023-08-01T17:58:18.763Z,1690912698.763 [Default:CheckIn:E] Running Loop=1
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.889494 min 
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn:E] Stopped
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn] Stopped
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn](INFO): Running loop #8
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn] Running Loop=8
2023-08-01T17:58:19.174Z,1690912699.174 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T17:58:19.175Z,1690912699.175 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T17:58:21.182Z,1690912701.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175759.00,A,4313.61447,N,08620.34183,W,0.330,335.13,010823,,,A*7F
2023-08-01T17:58:21.185Z,1690912701.185 [NAL9602](INFO): GPS fix at 20230801T175759: (43.226908, -86.339031)
2023-08-01T17:58:21.213Z,1690912701.213 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T17:58:21.213Z,1690912701.213 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T17:58:28.583Z,1690912708.583 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20230801T171122/Courier0025.lzma
2023-08-01T17:58:29.586Z,1690912709.586 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Courier0025.lzma.bak
2023-08-01T17:58:29.586Z,1690912709.586 [DataOverHttps](INFO): SBD MOMSN=18505513
2023-08-01T17:58:46.336Z,1690912726.336 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230801T171122/Express0026.lzma
2023-08-01T17:58:47.338Z,1690912727.338 [DataOverHttps](INFO): Moved sent file to Logs/20230801T171122/Express0026.lzma.bak
2023-08-01T17:58:47.338Z,1690912727.338 [DataOverHttps](INFO): SBD MOMSN=18505517
2023-08-01T17:58:49.589Z,1690912729.589 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T17:58:49.589Z,1690912729.589 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T17:58:49.589Z,1690912729.589 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T17:58:51.601Z,1690912731.601 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-01T17:58:51.684Z,1690912731.684 [NAL9602](ERROR): received: 
+CSQ:0

OK74, 0, 0, 0, 0

OK
2023-08-01T17:59:21.995Z,1690912761.995 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-08-01T17:59:21.995Z,1690912761.995 [DVL_micro] No Fault, FailCount= 3
2023-08-01T17:59:39.739Z,1690912779.739 [DVL_micro](FAULT): Failed to initialize within timeout.
2023-08-01T17:59:39.740Z,1690912779.740 [DVL_micro] Communications Fault, FailCount= 1
2023-08-01T17:59:39.740Z,1690912779.740 [DVL_micro](ERROR): Communications Fault
2023-08-01T17:59:39.780Z,1690912779.780 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-08-01T17:59:40.133Z,1690912780.133 [DVL_micro](INFO): Powering down
2023-08-01T17:59:40.983Z,1690912780.983 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-08-01T17:59:40.983Z,1690912780.983 [DVL_micro] No Fault, FailCount= 1
2023-08-01T17:59:58.708Z,1690912798.708 [DVL_micro](FAULT): Failed to initialize within timeout.
2023-08-01T17:59:58.708Z,1690912798.708 [DVL_micro] Communications Fault, FailCount= 2
2023-08-01T17:59:58.708Z,1690912798.708 [DVL_micro](ERROR): Communications Fault
2023-08-01T17:59:58.793Z,1690912798.793 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-08-01T17:59:59.189Z,1690912799.189 [DVL_micro](INFO): Powering down
2023-08-01T17:59:59.969Z,1690912799.969 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-08-01T17:59:59.969Z,1690912799.969 [DVL_micro] No Fault, FailCount= 2
2023-08-01T18:00:17.695Z,1690912817.695 [DVL_micro](FAULT): Failed to initialize within timeout.
2023-08-01T18:00:17.696Z,1690912817.696 [DVL_micro] Communications Fault, FailCount= 3
2023-08-01T18:00:17.696Z,1690912817.696 [DVL_micro](ERROR): Communications Fault
2023-08-01T18:00:17.741Z,1690912817.741 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-08-01T18:00:17.742Z,1690912817.742 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2023-08-01T18:00:18.176Z,1690912818.176 [DVL_micro](INFO): Powering down
2023-08-01T18:00:18.216Z,1690912818.216 [CBIT](INFO): Critical error at 20230801T180017
2023-08-01T18:03:10.945Z,1690912990.945 [DataOverHttps](IMPORTANT): SBD MTMSN=20230801T180249
2023-08-01T18:03:17.919Z,1690912997.919 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-08-01T18:03:17.919Z,1690912997.919 [DVL_micro] No Fault, FailCount= 3
2023-08-01T18:03:18.326Z,1690912998.326 [DataOverHttps](INFO): Received command: restart app
2023-08-01T18:03:18.341Z,1690912998.341 [CommandExec](IMPORTANT): got command restart application
2023-08-01T18:03:19.352Z,1690912999.352 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:19.352Z,1690912999.352 [CommandExec](INFO): Uninitializing the command executive.
2023-08-01T18:03:19.352Z,1690912999.352 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-01T18:03:19.353Z,1690912999.353 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:19.476Z,1690912999.476 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-01T18:03:19.476Z,1690912999.476 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-01T18:03:19.476Z,1690912999.476 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:19.477Z,1690912999.477 [NavChartDb](INFO): Join timeout helper Thread ID is 1249
2023-08-01T18:03:19.716Z,1690912999.716 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:19.716Z,1690912999.716 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:19.720Z,1690912999.720 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-01T18:03:19.720Z,1690912999.720 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:19.721Z,1690912999.721 [Radio_Surface](INFO): Join timeout helper Thread ID is 1250
2023-08-01T18:03:20.024Z,1690913000.024 [Radio_Surface](INFO): Powering down
2023-08-01T18:03:20.025Z,1690913000.025 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:20.025Z,1690913000.025 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:20.028Z,1690913000.028 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-01T18:03:20.028Z,1690913000.028 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:20.028Z,1690913000.028 [Onboard](INFO): Join timeout helper Thread ID is 1251
2023-08-01T18:03:20.300Z,1690913000.300 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-08-01T18:03:23.136Z,1690913003.136 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-01T18:03:23.156Z,1690913003.156 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:23.156Z,1690913003.156 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.172Z,1690913003.172 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-01T18:03:23.172Z,1690913003.172 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.172Z,1690913003.172 [DataOverHttps](INFO): Join timeout helper Thread ID is 1252
2023-08-01T18:03:23.312Z,1690913003.312 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:23.312Z,1690913003.312 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.324Z,1690913003.324 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-08-01T18:03:23.324Z,1690913003.324 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.324Z,1690913003.324 [BackseatComponent](INFO): Join timeout helper Thread ID is 1253
2023-08-01T18:03:23.476Z,1690913003.476 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:23.476Z,1690913003.476 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.485Z,1690913003.485 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2023-08-01T18:03:23.485Z,1690913003.485 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.486Z,1690913003.486 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1254
2023-08-01T18:03:23.568Z,1690913003.568 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:23.568Z,1690913003.568 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.585Z,1690913003.585 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-01T18:03:23.585Z,1690913003.585 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.585Z,1690913003.585 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1255
2023-08-01T18:03:23.808Z,1690913003.808 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:23.808Z,1690913003.808 [WetLabsBB2FL](INFO): Powering down
2023-08-01T18:03:23.809Z,1690913003.809 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.819Z,1690913003.819 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-01T18:03:23.819Z,1690913003.819 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:23.833Z,1690913003.833 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1256
2023-08-01T18:03:24.312Z,1690913004.312 [CTD_Seabird](INFO): Powering down
2023-08-01T18:03:24.324Z,1690913004.324 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:24.324Z,1690913004.324 [CTD_Seabird](INFO): Powering down
2023-08-01T18:03:24.336Z,1690913004.336 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:24.340Z,1690913004.340 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-01T18:03:24.340Z,1690913004.340 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:24.352Z,1690913004.352 [logger](INFO): Join timeout helper Thread ID is 1257
2023-08-01T18:03:24.391Z,1690913004.391 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:24.391Z,1690913004.391 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:24.397Z,1690913004.397 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-01T18:03:24.397Z,1690913004.397 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:24.397Z,1690913004.397 [CommandLine](INFO): Join timeout helper Thread ID is 1258
2023-08-01T18:03:24.408Z,1690913004.408 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:24.408Z,1690913004.408 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:24.417Z,1690913004.417 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-01T18:03:24.417Z,1690913004.417 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:24.417Z,1690913004.417 [CommandExec](INFO): Join timeout helper Thread ID is 1259
2023-08-01T18:03:24.418Z,1690913004.418 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-01T18:03:24.418Z,1690913004.418 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:24.419Z,1690913004.419 [controlThread](INFO): Join timeout helper Thread ID is 1260
2023-08-01T18:03:24.732Z,1690913004.732 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T18:03:24.732Z,1690913004.732 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-01T18:03:24.873Z,1690913004.873 [AHRS_M2](INFO): Powering down
2023-08-01T18:03:25.016Z,1690913005.016 [DVL_micro](INFO): Powering down
2023-08-01T18:03:25.017Z,1690913005.017 [NAL9602](INFO): Powering down
2023-08-01T18:03:25.018Z,1690913005.018 [Sonardyne_Nano](INFO): Powering down
2023-08-01T18:03:25.136Z,1690913005.136 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-01T18:03:25.137Z,1690913005.137 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-01T18:03:25.138Z,1690913005.138 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-01T18:03:25.138Z,1690913005.138 [MissionManager](INFO): Uninitializing Mission Default
2023-08-01T18:03:25.138Z,1690913005.138 [Default] Stopped
2023-08-01T18:03:25.139Z,1690913005.139 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-01T18:03:25.139Z,1690913005.139 [Default:B.GoToSurface] Stopped
2023-08-01T18:03:25.139Z,1690913005.139 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-01T18:03:25.139Z,1690913005.139 [Default:CheckIn] Stopped
2023-08-01T18:03:25.139Z,1690913005.139 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T18:03:25.139Z,1690913005.139 [Default:CheckIn:C.Wait] Stopped
2023-08-01T18:03:25.139Z,1690913005.139 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T18:03:25.142Z,1690913005.142 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-01T18:03:25.142Z,1690913005.142 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-01T18:03:25.142Z,1690913005.142 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-01T18:03:25.143Z,1690913005.143 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-01T18:03:25.143Z,1690913005.143 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-01T18:03:25.144Z,1690913005.144 [BuoyancyServo](INFO): Powering down
2023-08-01T18:03:25.156Z,1690913005.156 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-01T18:03:25.156Z,1690913005.156 [ElevatorServo](INFO): Powering down
2023-08-01T18:03:25.157Z,1690913005.157 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-01T18:03:25.157Z,1690913005.157 [MassServo](INFO): Powering down
2023-08-01T18:03:25.158Z,1690913005.158 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-01T18:03:25.158Z,1690913005.158 [RudderServo](INFO): Powering down
2023-08-01T18:03:25.159Z,1690913005.159 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-01T18:03:25.159Z,1690913005.159 [ThrusterHE](INFO): Powering down
2023-08-01T18:03:25.160Z,1690913005.160 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-01T18:03:25.160Z,1690913005.160 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-01T18:03:25.161Z,1690913005.161 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-01T18:03:25.161Z,1690913005.161 [CBIT](DEBUG): Powering off loads.
2023-08-01T18:03:25.172Z,1690913005.172 [CBIT](DEBUG): Disabling WDT.
2023-08-01T18:03:25.184Z,1690913005.184 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-01T18:03:25.185Z,1690913005.185 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.237Z,1690913005.237 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.241Z,1690913005.241 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.247Z,1690913005.247 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.282Z,1690913005.282 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.284Z,1690913005.284 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.291Z,1690913005.291 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.336Z,1690913005.336 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.394Z,1690913005.394 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T18:03:25.460Z,1690913005.460 [logger ThreadHandler](INFO): Thread cancelled.