2024-09-16T18:06:19.654Z,1726509979.654 [Supervisor](DEBUG): Initializing supervisor.
2024-09-16T18:06:19.658Z,1726509979.658 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-16T18:06:19.659Z,1726509979.659 [SyncHandler](INFO): Protected caller Thread ID is 835
2024-09-16T18:06:19.659Z,1726509979.659 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-16T18:06:19.660Z,1726509979.660 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-16T18:06:19.660Z,1726509979.660 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2024-09-16T18:06:19.664Z,1726509979.664 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-16T18:06:19.682Z,1726509979.682 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-16T18:06:19.683Z,1726509979.683 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-16T18:06:19.684Z,1726509979.684 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2024-09-16T18:06:19.688Z,1726509979.688 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-16T18:06:19.689Z,1726509979.689 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-16T18:06:19.689Z,1726509979.689 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2024-09-16T18:06:19.691Z,1726509979.691 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-16T18:06:19.692Z,1726509979.692 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-16T18:06:19.693Z,1726509979.693 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2024-09-16T18:06:19.696Z,1726509979.696 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-16T18:06:19.697Z,1726509979.697 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-16T18:06:19.701Z,1726509979.701 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-16T18:06:20.054Z,1726509980.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-16T18:06:20.055Z,1726509980.055 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-16T18:06:21.103Z,1726509981.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-16T18:06:21.105Z,1726509981.105 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-16T18:06:21.306Z,1726509981.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-16T18:06:21.308Z,1726509981.308 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-16T18:06:22.183Z,1726509982.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-16T18:06:22.185Z,1726509982.185 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-16T18:06:22.294Z,1726509982.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-16T18:06:22.296Z,1726509982.296 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-16T18:06:22.389Z,1726509982.389 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-16T18:06:22.835Z,1726509982.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-16T18:06:22.836Z,1726509982.836 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-16T18:06:23.700Z,1726509983.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-16T18:06:23.701Z,1726509983.701 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-16T18:06:23.885Z,1726509983.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-16T18:06:23.886Z,1726509983.886 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-16T18:06:24.153Z,1726509984.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-16T18:06:24.155Z,1726509984.155 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-16T18:06:24.731Z,1726509984.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-16T18:06:24.732Z,1726509984.732 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-16T18:06:25.140Z,1726509985.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-16T18:06:25.142Z,1726509985.142 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-16T18:06:25.236Z,1726509985.236 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-16T18:06:25.450Z,1726509985.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-16T18:06:25.450Z,1726509985.450 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-16T18:06:25.700Z,1726509985.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-16T18:06:25.701Z,1726509985.701 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-16T18:06:26.019Z,1726509986.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-16T18:06:26.021Z,1726509986.021 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2024-09-16T18:06:26.025Z,1726509986.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2024-09-16T18:06:26.147Z,1726509986.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2024-09-16T18:06:26.312Z,1726509986.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2024-09-16T18:06:26.411Z,1726509986.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2024-09-16T18:06:26.507Z,1726509986.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2024-09-16T18:06:26.631Z,1726509986.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2024-09-16T18:06:26.728Z,1726509986.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2024-09-16T18:06:26.847Z,1726509986.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2024-09-16T18:06:27.122Z,1726509987.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-16T18:06:27.123Z,1726509987.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2024-09-16T18:06:27.272Z,1726509987.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2024-09-16T18:06:27.378Z,1726509987.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2024-09-16T18:06:27.482Z,1726509987.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2024-09-16T18:06:27.591Z,1726509987.591 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-16T18:06:27.610Z,1726509987.610 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-16T18:06:27.750Z,1726509987.750 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-16T18:06:27.750Z,1726509987.750 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-16T18:06:27.766Z,1726509987.766 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-16T18:06:27.766Z,1726509987.766 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-16T18:06:27.837Z,1726509987.837 [DepthRateCalculator] Loaded
2024-09-16T18:06:27.837Z,1726509987.837 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-16T18:06:27.843Z,1726509987.843 [PitchRateCalculator] Loaded
2024-09-16T18:06:27.843Z,1726509987.843 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-16T18:06:27.853Z,1726509987.853 [SpeedCalculator] Loaded
2024-09-16T18:06:27.854Z,1726509987.854 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-16T18:06:27.859Z,1726509987.859 [YawRateCalculator] Loaded
2024-09-16T18:06:27.859Z,1726509987.859 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-16T18:06:27.880Z,1726509987.880 [ElevatorOffsetCalculator] Loaded
2024-09-16T18:06:27.880Z,1726509987.880 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-16T18:06:27.881Z,1726509987.881 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-16T18:06:27.881Z,1726509987.881 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-16T18:06:28.595Z,1726509988.595 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-16T18:06:28.651Z,1726509988.651 [VerticalControl] Loaded
2024-09-16T18:06:28.651Z,1726509988.651 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-16T18:06:28.654Z,1726509988.654 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-16T18:06:28.907Z,1726509988.907 [HorizontalControl] Loaded
2024-09-16T18:06:28.907Z,1726509988.907 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-16T18:06:28.910Z,1726509988.910 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-16T18:06:28.915Z,1726509988.915 [SpeedControl] Loaded
2024-09-16T18:06:28.915Z,1726509988.915 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-16T18:06:28.918Z,1726509988.918 [LoopControl](DEBUG): Construct LoopControl.
2024-09-16T18:06:28.919Z,1726509988.919 [LoopControl] Loaded
2024-09-16T18:06:28.919Z,1726509988.919 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-16T18:06:28.919Z,1726509988.919 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-16T18:06:28.920Z,1726509988.920 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-16T18:06:29.060Z,1726509989.060 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-16T18:06:29.061Z,1726509989.061 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-16T18:06:29.075Z,1726509989.075 [NavChart] Loaded
2024-09-16T18:06:29.075Z,1726509989.075 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-16T18:06:29.080Z,1726509989.080 [UniversalFixResidualReporter] Loaded
2024-09-16T18:06:29.081Z,1726509989.081 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-16T18:06:29.081Z,1726509989.081 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-16T18:06:29.082Z,1726509989.082 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-16T18:06:29.293Z,1726509989.293 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-16T18:06:29.294Z,1726509989.294 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-16T18:06:30.112Z,1726509990.112 [AHRS_M2] Loaded
2024-09-16T18:06:30.112Z,1726509990.112 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-16T18:06:30.365Z,1726509990.365 [BackseatComponent] Loaded
2024-09-16T18:06:30.365Z,1726509990.365 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-16T18:06:30.366Z,1726509990.366 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409384E0
2024-09-16T18:06:30.367Z,1726509990.367 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2024-09-16T18:06:30.370Z,1726509990.370 [LcmUniversalReporter] Loaded
2024-09-16T18:06:30.370Z,1726509990.370 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-16T18:06:31.199Z,1726509991.199 [BPC1] Loaded
2024-09-16T18:06:31.200Z,1726509991.200 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-16T18:06:31.291Z,1726509991.291 [DAT] Loaded
2024-09-16T18:06:31.292Z,1726509991.292 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-09-16T18:06:31.293Z,1726509991.293 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409684E0
2024-09-16T18:06:31.293Z,1726509991.293 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2024-09-16T18:06:31.433Z,1726509991.433 [DataOverHttps] Loaded
2024-09-16T18:06:31.433Z,1726509991.433 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-16T18:06:31.434Z,1726509991.434 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409984E0
2024-09-16T18:06:31.435Z,1726509991.435 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926
2024-09-16T18:06:31.458Z,1726509991.458 [Depth_Keller] Loaded
2024-09-16T18:06:31.458Z,1726509991.458 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-16T18:06:31.497Z,1726509991.497 [DVL_micro] Loaded
2024-09-16T18:06:31.497Z,1726509991.497 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-09-16T18:06:31.560Z,1726509991.560 [NAL9602] Loaded
2024-09-16T18:06:31.561Z,1726509991.561 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-16T18:06:31.591Z,1726509991.591 [Onboard] Loaded
2024-09-16T18:06:31.591Z,1726509991.591 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-16T18:06:31.592Z,1726509991.592 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C84E0
2024-09-16T18:06:31.592Z,1726509991.592 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2024-09-16T18:06:31.606Z,1726509991.606 [Power24vConverter] Loaded
2024-09-16T18:06:31.607Z,1726509991.607 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-16T18:06:31.622Z,1726509991.622 [Radio_Surface] Loaded
2024-09-16T18:06:31.622Z,1726509991.622 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-16T18:06:31.623Z,1726509991.623 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F84E0
2024-09-16T18:06:31.624Z,1726509991.624 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2024-09-16T18:06:31.639Z,1726509991.639 [Sonardyne_Nano] Loaded
2024-09-16T18:06:31.639Z,1726509991.639 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-16T18:06:31.640Z,1726509991.640 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-16T18:06:31.640Z,1726509991.640 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-16T18:06:31.802Z,1726509991.802 [BuoyancyServo] Loaded
2024-09-16T18:06:31.802Z,1726509991.802 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-16T18:06:31.825Z,1726509991.825 [ElevatorServo] Loaded
2024-09-16T18:06:31.825Z,1726509991.825 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-16T18:06:31.847Z,1726509991.847 [MassServo] Loaded
2024-09-16T18:06:31.847Z,1726509991.847 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-16T18:06:31.867Z,1726509991.867 [RudderServo] Loaded
2024-09-16T18:06:31.868Z,1726509991.868 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-16T18:06:31.884Z,1726509991.884 [ThrusterHE] Loaded
2024-09-16T18:06:31.884Z,1726509991.884 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-16T18:06:31.885Z,1726509991.885 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-16T18:06:31.886Z,1726509991.886 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-16T18:06:31.984Z,1726509991.984 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-16T18:06:31.984Z,1726509991.984 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-16T18:06:32.027Z,1726509992.027 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-16T18:06:32.027Z,1726509992.027 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-16T18:06:32.464Z,1726509992.464 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-16T18:06:32.464Z,1726509992.464 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-16T18:06:32.688Z,1726509992.688 [CTD_Seabird] Loaded
2024-09-16T18:06:32.689Z,1726509992.689 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-16T18:06:32.690Z,1726509992.690 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB24E0
2024-09-16T18:06:32.690Z,1726509992.690 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930
2024-09-16T18:06:32.726Z,1726509992.726 [ESPComponent] Loaded
2024-09-16T18:06:32.726Z,1726509992.726 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-16T18:06:32.748Z,1726509992.748 [PAR_Licor] Loaded
2024-09-16T18:06:32.748Z,1726509992.748 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-16T18:06:32.793Z,1726509992.793 [WetLabsBB2FL] Loaded
2024-09-16T18:06:32.793Z,1726509992.793 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-16T18:06:32.794Z,1726509992.794 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE24E0
2024-09-16T18:06:32.795Z,1726509992.795 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931
2024-09-16T18:06:32.795Z,1726509992.795 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-16T18:06:32.796Z,1726509992.796 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-16T18:06:32.965Z,1726509992.965 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-16T18:06:32.975Z,1726509992.975 [SBIT] Loaded
2024-09-16T18:06:32.975Z,1726509992.975 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-16T18:06:32.978Z,1726509992.978 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-16T18:06:32.990Z,1726509992.990 [IBIT] Loaded
2024-09-16T18:06:32.991Z,1726509992.991 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-16T18:06:32.997Z,1726509992.997 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-16T18:06:33.102Z,1726509993.102 [CBIT] Loaded
2024-09-16T18:06:33.102Z,1726509993.102 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-16T18:06:33.103Z,1726509993.103 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-16T18:06:33.109Z,1726509993.109 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-16T18:06:33.112Z,1726509993.112 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-16T18:06:33.124Z,1726509993.124 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-16T18:06:33.125Z,1726509993.125 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C514E0
2024-09-16T18:06:33.125Z,1726509993.125 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 932
2024-09-16T18:06:33.130Z,1726509993.130 [Supervisor](INFO): Main Thread ID is 828
2024-09-16T18:06:33.130Z,1726509993.130 [Supervisor](DEBUG): Running supervisor.
2024-09-16T18:06:33.130Z,1726509993.130 [CommandExec ThreadHandler](INFO): Handler Thread ID is 933
2024-09-16T18:06:33.131Z,1726509993.131 [CommandExec](INFO): Initializing the command executive.
2024-09-16T18:06:33.132Z,1726509993.132 [CommandLine ThreadHandler](INFO): Handler Thread ID is 934
2024-09-16T18:06:33.134Z,1726509993.134 [controlThread ThreadHandler](INFO): Handler Thread ID is 935
2024-09-16T18:06:33.135Z,1726509993.135 [controlThread](DEBUG): Initializing ControlThread
2024-09-16T18:06:33.136Z,1726509993.136 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-16T18:06:33.136Z,1726509993.136 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-16T18:06:33.136Z,1726509993.136 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-16T18:06:33.137Z,1726509993.137 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-16T18:06:33.137Z,1726509993.137 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-16T18:06:33.138Z,1726509993.138 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-16T18:06:33.139Z,1726509993.139 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-16T18:06:33.140Z,1726509993.140 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-16T18:06:33.140Z,1726509993.140 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-16T18:06:33.141Z,1726509993.141 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-16T18:06:33.142Z,1726509993.142 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-16T18:06:33.150Z,1726509993.150 [SBIT](INFO): Initialize SBIT Component.
2024-09-16T18:06:33.150Z,1726509993.150 [SBIT](IMPORTANT): git: 2024-09-16_B
2024-09-16T18:06:33.150Z,1726509993.150 [SBIT](INFO): git hash: 99f9462fcf59f51f8462e4e844b71b4239fb11a8
2024-09-16T18:06:33.151Z,1726509993.151 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-16T18:06:33.152Z,1726509993.152 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-09-16T18:06:33.153Z,1726509993.153 [SBIT](INFO): Beginning SBIT in 35.000000 seconds.
2024-09-16T18:06:33.154Z,1726509993.154 [IBIT](INFO): Initialize IBIT Component.
2024-09-16T18:06:33.155Z,1726509993.155 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-16T18:06:33.155Z,1726509993.155 [logger ThreadHandler](INFO): Handler Thread ID is 936
2024-09-16T18:06:33.165Z,1726509993.165 [CBIT](DEBUG): Initialized mux pins.
2024-09-16T18:06:33.166Z,1726509993.166 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-16T18:06:33.174Z,1726509993.174 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937
2024-09-16T18:06:33.186Z,1726509993.186 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2024-09-16T18:06:33.187Z,1726509993.187 [DAT](INFO): Powering up
2024-09-16T18:06:33.187Z,1726509993.187 [DAT](DEBUG): Initializing DAT.
2024-09-16T18:06:33.190Z,1726509993.190 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-16T18:06:33.190Z,1726509993.190 [CBIT](DEBUG): Initializing heartbeat.
2024-09-16T18:06:33.192Z,1726509993.192 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 940
2024-09-16T18:06:33.193Z,1726509993.193 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-16T18:06:33.202Z,1726509993.202 [Onboard ThreadHandler](INFO): Handler Thread ID is 941
2024-09-16T18:06:33.220Z,1726509993.220 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 942
2024-09-16T18:06:33.242Z,1726509993.242 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 943
2024-09-16T18:06:33.243Z,1726509993.243 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-16T18:06:33.247Z,1726509993.247 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 945
2024-09-16T18:06:33.250Z,1726509993.250 [WetLabsBB2FL](INFO): Powering up
2024-09-16T18:06:33.251Z,1726509993.251 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 947
2024-09-16T18:06:33.259Z,1726509993.259 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-09-16T18:06:33.259Z,1726509993.259 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-09-16T18:06:33.260Z,1726509993.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-09-16T18:06:33.260Z,1726509993.260 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-09-16T18:06:33.260Z,1726509993.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-09-16T18:06:33.260Z,1726509993.260 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-09-16T18:06:33.260Z,1726509993.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-09-16T18:06:33.260Z,1726509993.260 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-09-16T18:06:33.262Z,1726509993.262 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-16T18:06:33.262Z,1726509993.262 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-16T18:06:33.301Z,1726509993.301 [CBIT](DEBUG): Backplane powered.
2024-09-16T18:06:33.302Z,1726509993.302 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-16T18:06:33.303Z,1726509993.303 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-16T18:06:33.303Z,1726509993.303 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-16T18:06:33.309Z,1726509993.309 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-16T18:06:33.379Z,1726509993.379 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-16T18:06:33.384Z,1726509993.384 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T18:06:33.427Z,1726509993.427 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-09-16T18:06:33.428Z,1726509993.428 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-16T18:06:33.428Z,1726509993.428 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-16T18:06:33.432Z,1726509993.432 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-16T18:06:33.626Z,1726509993.626 [Radio_Surface](INFO): Powering up
2024-09-16T18:06:33.744Z,1726509993.744 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-09-16T18:06:33.749Z,1726509993.749 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-16T18:06:33.770Z,1726509993.770 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-16T18:06:33.773Z,1726509993.773 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T18:06:33.801Z,1726509993.801 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-16T18:06:33.803Z,1726509993.803 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-16T18:06:33.826Z,1726509993.826 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-16T18:06:33.828Z,1726509993.828 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-09-16T18:06:33.845Z,1726509993.845 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-09-16T18:06:33.858Z,1726509993.858 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-16T18:06:34.028Z,1726509994.028 [Power24vConverter](INFO): Powering up.
2024-09-16T18:06:34.028Z,1726509994.028 [Sonardyne_Nano](INFO): Initializing.
2024-09-16T18:06:34.111Z,1726509994.111 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:34.118Z,1726509994.118 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-16T18:06:34.119Z,1726509994.119 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:34.126Z,1726509994.126 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-16T18:06:34.127Z,1726509994.127 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:34.134Z,1726509994.134 [MassServo](DEBUG): Initializing MassServo.
2024-09-16T18:06:34.135Z,1726509994.135 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:34.142Z,1726509994.142 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-16T18:06:34.143Z,1726509994.143 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-16T18:06:34.150Z,1726509994.150 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-16T18:06:35.862Z,1726509995.862 [WetLabsBB2FL](INFO): Powering down
2024-09-16T18:06:36.280Z,1726509996.280 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-16T18:06:37.518Z,1726509997.518 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2024-09-16T18:06:39.639Z,1726509999.639 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-16T18:06:40.274Z,1726510000.274 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-09-16T18:06:45.282Z,1726510005.282 [DAT](INFO): DAT read:
2024-09-16T18:06:45.283Z,1726510005.283 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-16T18:06:47.046Z,1726510007.046 [DAT](INFO): DAT read: MF Frequency Band
2024-09-16T18:06:47.047Z,1726510007.047 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-16T18:06:47.048Z,1726510007.048 [DAT](INFO): DAT read: Sep 16 2024 18:06:34
2024-09-16T18:06:48.307Z,1726510008.307 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-09-16T18:06:48.308Z,1726510008.308 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2024-09-16T18:06:48.308Z,1726510008.308 [DAT](INFO): commRate: 600
2024-09-16T18:06:50.374Z,1726510010.374 [DAT](INFO): entering command mode
2024-09-16T18:06:50.574Z,1726510010.574 [DAT](INFO): DAT read:
2024-09-16T18:06:50.575Z,1726510010.575 [DAT](INFO): DAT read: user:1>
2024-09-16T18:06:50.575Z,1726510010.575 [DAT](INFO): setting verbose to 3
2024-09-16T18:06:50.826Z,1726510010.826 [DAT](INFO): DAT read: user:1>
2024-09-16T18:06:50.827Z,1726510010.827 [DAT](INFO): DAT read: Verbose | 3
2024-09-16T18:06:50.827Z,1726510010.827 [DAT](INFO): set verbose to 3
2024-09-16T18:06:50.828Z,1726510010.828 [DAT](INFO): setting DatVerbose to 27440
2024-09-16T18:06:51.078Z,1726510011.078 [DAT](INFO): DAT read: user:2>
2024-09-16T18:06:51.079Z,1726510011.079 [DAT](INFO): DAT read: DatVerbose | 27440
2024-09-16T18:06:51.079Z,1726510011.079 [DAT](INFO): set DatVerbose to 27440
2024-09-16T18:06:51.080Z,1726510011.080 [DAT](INFO): setting transmit power to 8
2024-09-16T18:06:51.330Z,1726510011.330 [DAT](INFO): DAT read: user:3>
2024-09-16T18:06:51.331Z,1726510011.331 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-09-16T18:06:51.331Z,1726510011.331 [DAT](INFO): set transmit power to 8
2024-09-16T18:06:51.332Z,1726510011.332 [DAT](INFO): setting local address to 11
2024-09-16T18:06:51.582Z,1726510011.582 [DAT](INFO): DAT read: user:4>
2024-09-16T18:06:51.583Z,1726510011.583 [DAT](INFO): DAT read: LocalAddr | 11
2024-09-16T18:06:51.583Z,1726510011.583 [DAT](INFO): set local address to 11
2024-09-16T18:06:51.584Z,1726510011.584 [DAT](INFO): Setting time to: 18:6:51 And date to:9/16/2024
2024-09-16T18:06:51.834Z,1726510011.834 [DAT](INFO): DAT read: user:5>
2024-09-16T18:06:51.835Z,1726510011.835 [DAT](INFO): DAT read: Mon Sep 16, 2024 18:06:51
2024-09-16T18:06:51.835Z,1726510011.835 [DAT](INFO): Local DAT time set to Mon Sep 16, 2024 18:06:51
2024-09-16T18:07:01.736Z,1726510021.736 [NAL9602](INFO): Powering up NAL9602
2024-09-16T18:07:08.632Z,1726510028.632 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-16T18:07:08.640Z,1726510028.640 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T18:07:12.652Z,1726510032.652 [NAL9602](INFO): NAL9602 initialized
2024-09-16T18:07:19.602Z,1726510039.602 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.031096
CHAN A1 (24V): 0.016436
CHAN A2 (12V): -0.015451
CHAN A3 (5V): -0.021705
CHAN B0 (3.3V): -0.009495
CHAN B1 (3.15aV): -0.004096
CHAN B2 (3.15bV): -0.001068
CHAN B3 (GND): -0.008932
OPEN: -0.000441
Full Scale: +/- 1 mA
2024-09-16T18:07:53.846Z,1726510073.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:02.788Z,1726510082.788 [SBIT](IMPORTANT): SBIT PASSED
2024-09-16T18:08:02.788Z,1726510082.788 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-16T18:08:02.789Z,1726510082.789 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;
2024-09-16T18:08:02.789Z,1726510082.789 [SBIT](IMPORTANT): DATMMP.loadAtStartup=0 bool;
2024-09-16T18:08:02.789Z,1726510082.789 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=311.101367 cubic_centimeter;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=1.25 second;
2024-09-16T18:08:02.790Z,1726510082.790 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=1.25 reciprocal_second;
2024-09-16T18:08:02.791Z,1726510082.791 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=1.25 none;
2024-09-16T18:08:02.791Z,1726510082.791 [SBIT](IMPORTANT): VerticalControl.massDefault=3.894407 millimeter;
2024-09-16T18:08:02.791Z,1726510082.791 [SBIT](IMPORTANT): VerticalControl.massOnlyForceInt=0 bool;
2024-09-16T18:08:03.180Z,1726510083.180 [MissionManager](IMPORTANT): Started mission Startup
2024-09-16T18:08:03.180Z,1726510083.180 [Startup] Running Loop=1
2024-09-16T18:08:03.180Z,1726510083.180 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-16T18:08:03.180Z,1726510083.180 [Startup:A.GoToSurface] Running Loop=1
2024-09-16T18:08:03.180Z,1726510083.180 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T18:08:03.181Z,1726510083.181 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T18:08:03.181Z,1726510083.181 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T18:08:03.182Z,1726510083.182 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T18:08:03.182Z,1726510083.182 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T18:08:03.182Z,1726510083.182 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T18:08:03.183Z,1726510083.183 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T18:08:03.184Z,1726510083.184 [Startup:StartupSatComms] Running Loop=1
2024-09-16T18:08:03.184Z,1726510083.184 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-16T18:08:03.185Z,1726510083.185 [Startup:StartupSatComms:A] Running Loop=1
2024-09-16T18:08:03.592Z,1726510083.592 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-16T18:08:07.582Z,1726510087.582 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:21.723Z,1726510101.723 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:35.458Z,1726510115.458 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:08:49.600Z,1726510129.600 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:09:03.362Z,1726510143.362 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:09:03.380Z,1726510143.380 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-16T18:08:03.2Z
2024-09-16T18:09:03.380Z,1726510143.380 [Startup:StartupSatComms:A] Stopped
2024-09-16T18:09:03.380Z,1726510143.380 [Startup:StartupSatComms:B] Running Loop=1
2024-09-16T18:09:03.768Z,1726510143.768 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T18:09:17.474Z,1726510157.474 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:09:31.210Z,1726510171.210 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:09:33.266Z,1726510173.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-09-16T18:09:33.266Z,1726510173.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T18:09:33.277Z,1726510173.277 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T18:09:33.721Z,1726510173.721 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T18:09:33.723Z,1726510173.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-09-16T18:09:45.351Z,1726510185.351 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:09:59.086Z,1726510199.086 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:10:03.560Z,1726510203.560 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-16T18:09:03.4Z
2024-09-16T18:10:03.560Z,1726510203.560 [Startup:StartupSatComms:B] Stopped
2024-09-16T18:10:03.560Z,1726510203.560 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-16T18:10:03.560Z,1726510203.560 [Startup:StartupSatComms] Stopped
2024-09-16T18:10:03.560Z,1726510203.560 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-16T18:10:03.561Z,1726510203.561 [Startup](INFO): Completed Startup
2024-09-16T18:10:03.562Z,1726510203.562 [MissionManager](INFO): Startup is completed.
2024-09-16T18:10:03.562Z,1726510203.562 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-16T18:10:03.562Z,1726510203.562 [Startup] Stopped
2024-09-16T18:10:03.562Z,1726510203.562 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-16T18:10:03.562Z,1726510203.562 [Startup:A.GoToSurface] Stopped
2024-09-16T18:10:03.562Z,1726510203.562 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T18:10:03.964Z,1726510203.964 [MissionManager](IMPORTANT): Started mission Default
2024-09-16T18:10:03.964Z,1726510203.964 [Default] Running Loop=1
2024-09-16T18:10:03.964Z,1726510203.964 [Default](DEBUG): Aggregate::initialize Default
2024-09-16T18:10:03.964Z,1726510203.964 [Default:B.GoToSurface] Running Loop=1
2024-09-16T18:10:03.964Z,1726510203.964 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T18:10:03.965Z,1726510203.965 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T18:10:03.965Z,1726510203.965 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T18:10:03.965Z,1726510203.965 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T18:10:03.966Z,1726510203.966 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T18:10:03.966Z,1726510203.966 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T18:10:03.966Z,1726510203.966 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T18:10:03.967Z,1726510203.967 [Default:A.Wait] Running Loop=1
2024-09-16T18:10:03.967Z,1726510203.967 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:10:13.228Z,1726510213.228 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:10:13.400Z,1726510213.400 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004479
2024-09-16T18:10:17.308Z,1726510217.308 [Default:A.Wait](INFO): Done Waiting.
2024-09-16T18:10:17.308Z,1726510217.308 [Default:A.Wait] Stopped
2024-09-16T18:10:17.308Z,1726510217.308 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:10:17.720Z,1726510217.720 [Default:CheckIn] Running Loop=1
2024-09-16T18:10:17.720Z,1726510217.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:10:17.720Z,1726510217.720 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:10:18.115Z,1726510218.115 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-16T18:12:15.714Z,1726510335.714 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T18:12:34.301Z,1726510354.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-09-16T18:12:34.301Z,1726510354.301 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T18:12:34.312Z,1726510354.312 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T18:12:34.752Z,1726510354.752 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T18:12:34.752Z,1726510354.752 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-09-16T18:15:00.202Z,1726510500.202 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-16T18:15:17.921Z,1726510517.921 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-16T18:10:17.7Z
2024-09-16T18:15:17.921Z,1726510517.921 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:15:17.921Z,1726510517.921 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:15:18.383Z,1726510518.383 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T18:15:26.265Z,1726510526.265 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240916T042323/Courier0243.lzma
2024-09-16T18:15:26.586Z,1726510526.586 [DataOverHttps](INFO): Moved sent file to Logs/20240916T042323/Courier0243.lzma.bak
2024-09-16T18:15:26.586Z,1726510526.586 [DataOverHttps](INFO): SBD MOMSN=23190875
2024-09-16T18:15:35.321Z,1726510535.321 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-09-16T18:15:35.321Z,1726510535.321 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T18:15:35.331Z,1726510535.331 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T18:15:35.717Z,1726510535.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T18:15:35.717Z,1726510535.717 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-09-16T18:15:42.328Z,1726510542.328 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240916T180619/Courier0000.lzma
2024-09-16T18:15:43.330Z,1726510543.330 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Courier0000.lzma.bak
2024-09-16T18:15:43.330Z,1726510543.330 [DataOverHttps](INFO): SBD MOMSN=23190877
2024-09-16T18:15:59.087Z,1726510559.087 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240916T180619/Courier0004.lzma
2024-09-16T18:16:00.090Z,1726510560.090 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Courier0004.lzma.bak
2024-09-16T18:16:00.090Z,1726510560.090 [DataOverHttps](INFO): SBD MOMSN=23190882
2024-09-16T18:16:16.483Z,1726510576.483 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240916T042323/Express0244.lzma
2024-09-16T18:16:17.487Z,1726510577.487 [DataOverHttps](INFO): Moved sent file to Logs/20240916T042323/Express0244.lzma.bak
2024-09-16T18:16:17.487Z,1726510577.487 [DataOverHttps](INFO): SBD MOMSN=23190888
2024-09-16T18:16:33.361Z,1726510593.361 [DataOverHttps](INFO): Sending 951 bytes from file Logs/20240916T180619/Express0001.lzma
2024-09-16T18:16:34.362Z,1726510594.362 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Express0001.lzma.bak
2024-09-16T18:16:34.362Z,1726510594.362 [DataOverHttps](INFO): SBD MOMSN=23190897
2024-09-16T18:16:37.926Z,1726510597.926 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2024-09-16T18:16:50.772Z,1726510610.772 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240916T180619/Express0005.lzma
2024-09-16T18:16:51.774Z,1726510611.774 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Express0005.lzma.bak
2024-09-16T18:16:51.774Z,1726510611.774 [DataOverHttps](INFO): SBD MOMSN=23190930
2024-09-16T18:16:53.682Z,1726510613.682 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:16:53.682Z,1726510613.682 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:16:53.682Z,1726510613.682 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:17:13.466Z,1726510633.466 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-09-16T18:17:13.466Z,1726510633.466 [NAL9602] Data Fault, FailCount= 1
2024-09-16T18:17:13.466Z,1726510633.466 [NAL9602](ERROR): Data Fault
2024-09-16T18:17:13.484Z,1726510633.484 [CBIT](ERROR): Data Fault in component: NAL9602
2024-09-16T18:17:13.895Z,1726510633.895 [NAL9602](INFO): Powering down
2024-09-16T18:17:14.274Z,1726510634.274 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2024-09-16T18:17:14.274Z,1726510634.274 [NAL9602] Hardware Fault, FailCount= 1
2024-09-16T18:17:14.274Z,1726510634.274 [NAL9602](ERROR): Hardware Fault
2024-09-16T18:17:14.755Z,1726510634.755 [CBIT](INFO): Clearing failed state for component NAL9602
2024-09-16T18:17:14.755Z,1726510634.755 [NAL9602] No Fault, FailCount= 1
2024-09-16T18:17:33.252Z,1726510653.252 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:17:44.162Z,1726510664.162 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:17:44.171Z,1726510664.171 [NAL9602](INFO): Powering up NAL9602
2024-09-16T18:17:47.399Z,1726510667.399 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:17:55.078Z,1726510675.078 [NAL9602](INFO): NAL9602 initialized
2024-09-16T18:17:58.301Z,1726510678.301 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:01.129Z,1726510681.129 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:12.457Z,1726510692.457 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:15.269Z,1726510695.269 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:26.177Z,1726510706.177 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:29.005Z,1726510709.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:36.299Z,1726510716.299 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-09-16T18:18:36.299Z,1726510716.299 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T18:18:36.338Z,1726510716.338 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T18:18:36.706Z,1726510716.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T18:18:36.707Z,1726510716.707 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-09-16T18:18:39.913Z,1726510719.913 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:43.145Z,1726510723.145 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:54.058Z,1726510734.058 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:18:56.881Z,1726510736.881 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:08.196Z,1726510748.196 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:11.022Z,1726510751.022 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:22.333Z,1726510762.333 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:24.757Z,1726510764.757 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:36.473Z,1726510776.473 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:38.898Z,1726510778.898 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:50.209Z,1726510790.209 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:19:52.633Z,1726510792.633 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:20:04.349Z,1726510804.349 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-16T18:20:16.901Z,1726510816.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182013.00,A,3648.16142,N,12147.29095,W,0.117,0.00,160924,,,A*75
2024-09-16T18:20:16.904Z,1726510816.904 [NAL9602](INFO): GPS fix at 20240916T182013: (36.802690, -121.788183)
2024-09-16T18:20:49.204Z,1726510849.204 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:21:54.255Z,1726510914.255 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:21:54.255Z,1726510914.255 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:21:54.255Z,1726510914.255 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:21:54.255Z,1726510914.255 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:21:54.697Z,1726510914.697 [Default:CheckIn:D] Stopped
2024-09-16T18:21:54.698Z,1726510914.698 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:21:55.078Z,1726510915.078 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.845555 min
2024-09-16T18:21:55.078Z,1726510915.078 [Default:CheckIn:E] Stopped
2024-09-16T18:21:55.078Z,1726510915.078 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:21:55.078Z,1726510915.078 [Default:CheckIn] Stopped
2024-09-16T18:21:55.078Z,1726510915.078 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:21:55.079Z,1726510915.079 [Default:CheckIn](INFO): Running loop #2
2024-09-16T18:21:55.079Z,1726510915.079 [Default:CheckIn] Running Loop=2
2024-09-16T18:21:55.079Z,1726510915.079 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:21:55.079Z,1726510915.079 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:21:57.085Z,1726510917.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182153.00,A,3648.16785,N,12147.27657,W,0.194,0.00,160924,,,A*70
2024-09-16T18:21:57.087Z,1726510917.087 [NAL9602](INFO): GPS fix at 20240916T182153: (36.802797, -121.787943)
2024-09-16T18:21:57.099Z,1726510917.099 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:21:57.099Z,1726510917.099 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:22:05.168Z,1726510925.168 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240916T180619/Courier0007.lzma
2024-09-16T18:22:06.170Z,1726510926.170 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Courier0007.lzma.bak
2024-09-16T18:22:06.170Z,1726510926.170 [DataOverHttps](INFO): SBD MOMSN=23190942
2024-09-16T18:22:22.116Z,1726510942.116 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20240916T180619/Express0008.lzma
2024-09-16T18:22:23.118Z,1726510943.118 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Express0008.lzma.bak
2024-09-16T18:22:23.118Z,1726510943.118 [DataOverHttps](INFO): SBD MOMSN=23190945
2024-09-16T18:22:24.985Z,1726510944.985 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:22:24.985Z,1726510944.985 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:22:24.985Z,1726510944.985 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:26:59.270Z,1726511219.270 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T18:27:24.312Z,1726511244.312 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:27:25.535Z,1726511245.535 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:27:25.535Z,1726511245.535 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:27:25.535Z,1726511245.535 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:27:25.535Z,1726511245.535 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:27:25.952Z,1726511245.952 [Default:CheckIn:D] Stopped
2024-09-16T18:27:25.952Z,1726511245.952 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:27:26.346Z,1726511246.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.366459 min
2024-09-16T18:27:26.346Z,1726511246.346 [Default:CheckIn:E] Stopped
2024-09-16T18:27:26.346Z,1726511246.346 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:27:26.346Z,1726511246.346 [Default:CheckIn] Stopped
2024-09-16T18:27:26.346Z,1726511246.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:27:26.346Z,1726511246.346 [Default:CheckIn](INFO): Running loop #3
2024-09-16T18:27:26.347Z,1726511246.347 [Default:CheckIn] Running Loop=3
2024-09-16T18:27:26.347Z,1726511246.347 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:27:26.347Z,1726511246.347 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:27:28.359Z,1726511248.359 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182727.00,A,3648.16693,N,12147.27415,W,0.078,249.03,160924,,,D*7D
2024-09-16T18:27:28.369Z,1726511248.369 [NAL9602](INFO): GPS fix at 20240916T182727: (36.802782, -121.787903)
2024-09-16T18:27:28.379Z,1726511248.379 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:27:28.379Z,1726511248.379 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:27:35.699Z,1726511255.699 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T180619/Courier0010.lzma
2024-09-16T18:27:36.702Z,1726511256.702 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Courier0010.lzma.bak
2024-09-16T18:27:36.702Z,1726511256.702 [DataOverHttps](INFO): SBD MOMSN=23190960
2024-09-16T18:27:38.047Z,1726511258.047 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:27:52.189Z,1726511272.189 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:27:52.548Z,1726511272.548 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240916T180619/Express0011.lzma
2024-09-16T18:27:53.550Z,1726511273.550 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Express0011.lzma.bak
2024-09-16T18:27:53.550Z,1726511273.550 [DataOverHttps](INFO): SBD MOMSN=23190966
2024-09-16T18:27:55.446Z,1726511275.446 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:27:55.446Z,1726511275.446 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:27:55.446Z,1726511275.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:28:00.285Z,1726511280.285 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:28:05.927Z,1726511285.927 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:28:20.065Z,1726511300.065 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:28:33.798Z,1726511313.798 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:28:47.942Z,1726511327.942 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:29:01.637Z,1726511341.637 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:29:15.778Z,1726511355.778 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:29:29.515Z,1726511369.515 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:29:43.660Z,1726511383.660 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:32:55.987Z,1726511575.987 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:32:55.987Z,1726511575.987 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:32:55.987Z,1726511575.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:32:55.988Z,1726511575.988 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:32:56.387Z,1726511576.387 [Default:CheckIn:D] Stopped
2024-09-16T18:32:56.387Z,1726511576.387 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:32:56.787Z,1726511576.787 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.873708 min
2024-09-16T18:32:56.787Z,1726511576.787 [Default:CheckIn:E] Stopped
2024-09-16T18:32:56.787Z,1726511576.787 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:32:56.787Z,1726511576.787 [Default:CheckIn] Stopped
2024-09-16T18:32:56.787Z,1726511576.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:32:56.788Z,1726511576.788 [Default:CheckIn](INFO): Running loop #4
2024-09-16T18:32:56.788Z,1726511576.788 [Default:CheckIn] Running Loop=4
2024-09-16T18:32:56.788Z,1726511576.788 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:32:56.788Z,1726511576.788 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:32:58.815Z,1726511578.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183258.00,A,3648.16560,N,12147.27795,W,0.039,292.71,160924,,,D*73
2024-09-16T18:32:58.817Z,1726511578.817 [NAL9602](INFO): GPS fix at 20240916T183258: (36.802760, -121.787966)
2024-09-16T18:32:58.832Z,1726511578.832 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:32:58.832Z,1726511578.832 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:33:07.420Z,1726511587.420 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180619/Courier0013.lzma
2024-09-16T18:33:08.421Z,1726511588.421 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Courier0013.lzma.bak
2024-09-16T18:33:08.421Z,1726511588.421 [DataOverHttps](INFO): SBD MOMSN=23190977
2024-09-16T18:33:24.299Z,1726511604.299 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240916T180619/Express0014.lzma
2024-09-16T18:33:25.301Z,1726511605.301 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Express0014.lzma.bak
2024-09-16T18:33:25.301Z,1726511605.301 [DataOverHttps](INFO): SBD MOMSN=23190980
2024-09-16T18:33:27.097Z,1726511607.097 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:33:27.097Z,1726511607.097 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:33:27.097Z,1726511607.097 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:33:29.509Z,1726511609.509 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-16T18:33:29.587Z,1726511609.587 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-09-16T18:33:42.812Z,1726511622.812 [CommandExec](IMPORTANT): got command burn on
2024-09-16T18:33:42.813Z,1726511622.813 [CommandExec](IMPORTANT): Activating dropweight wire
2024-09-16T18:34:06.800Z,1726511646.800 [CommandExec](IMPORTANT): got command burn off
2024-09-16T18:34:06.800Z,1726511646.800 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-09-16T18:37:19.374Z,1726511839.374 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:37:33.524Z,1726511853.524 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T18:37:34.336Z,1726511854.336 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5.
2024-09-16T18:37:34.340Z,1726511854.339 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-09-16T18:38:01.393Z,1726511881.393 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T18:38:27.662Z,1726511907.662 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:38:27.662Z,1726511907.662 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:38:27.662Z,1726511907.662 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:38:27.662Z,1726511907.662 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:38:28.069Z,1726511908.069 [Default:CheckIn:D] Stopped
2024-09-16T18:38:28.069Z,1726511908.069 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:38:28.489Z,1726511908.489 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.401742 min
2024-09-16T18:38:28.489Z,1726511908.489 [Default:CheckIn:E] Stopped
2024-09-16T18:38:28.489Z,1726511908.489 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:38:28.489Z,1726511908.489 [Default:CheckIn] Stopped
2024-09-16T18:38:28.490Z,1726511908.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:38:28.490Z,1726511908.490 [Default:CheckIn](INFO): Running loop #5
2024-09-16T18:38:28.490Z,1726511908.490 [Default:CheckIn] Running Loop=5
2024-09-16T18:38:28.490Z,1726511908.490 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:38:28.490Z,1726511908.490 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:38:30.486Z,1726511910.486 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183829.00,A,3648.16637,N,12147.28076,W,0.486,192.29,160924,,,D*75
2024-09-16T18:38:30.489Z,1726511910.489 [NAL9602](INFO): GPS fix at 20240916T183829: (36.802773, -121.788013)
2024-09-16T18:38:30.525Z,1726511910.525 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:38:30.525Z,1726511910.525 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:38:37.811Z,1726511917.811 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240916T180619/Courier0016.lzma
2024-09-16T18:38:38.813Z,1726511918.813 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Courier0016.lzma.bak
2024-09-16T18:38:38.813Z,1726511918.813 [DataOverHttps](INFO): SBD MOMSN=23191055
2024-09-16T18:38:51.761Z,1726511931.761 [CommandExec](IMPORTANT): got command strobe off
2024-09-16T18:38:51.761Z,1726511931.761 [CommandExec](IMPORTANT): Deactivating strobe
2024-09-16T18:38:55.648Z,1726511935.648 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20240916T180619/Express0017.lzma
2024-09-16T18:38:56.649Z,1726511936.649 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Express0017.lzma.bak
2024-09-16T18:38:56.649Z,1726511936.649 [DataOverHttps](INFO): SBD MOMSN=23191061
2024-09-16T18:38:58.375Z,1726511938.375 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:38:58.375Z,1726511938.375 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:38:58.375Z,1726511938.375 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:39:02.396Z,1726511942.396 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:41:43.432Z,1726512103.432 [CommandExec](IMPORTANT): got command failComponent
2024-09-16T18:41:43.432Z,1726512103.432 [CommandExec](IMPORTANT): Failed components:
2024-09-16T18:41:43.432Z,1726512103.432 [CommandExec](IMPORTANT): No failed Components.
2024-09-16T18:41:56.889Z,1726512116.889 [CommandExec](IMPORTANT): got command show variable nano
2024-09-16T18:41:56.965Z,1726512116.965 [CommandExec](IMPORTANT): NanoDVR.loadAtStartup (bool)
2024-09-16T18:41:56.965Z,1726512116.965 [CommandExec](IMPORTANT): NanoDVR.simulateHardware (bool)
2024-09-16T18:41:56.966Z,1726512116.966 [CommandExec](IMPORTANT): NanoDVR.sampleTime (second)
2024-09-16T18:41:56.974Z,1726512116.974 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool)
2024-09-16T18:41:56.975Z,1726512116.975 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool)
2024-09-16T18:41:57.007Z,1726512117.007 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent)
2024-09-16T18:41:57.008Z,1726512117.008 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent)
2024-09-16T18:41:57.113Z,1726512117.113 [CommandExec](IMPORTANT): NanoDVR.loadControl (none)
2024-09-16T18:41:57.113Z,1726512117.113 [CommandExec](IMPORTANT): NanoDVR.uart (none)
2024-09-16T18:41:57.113Z,1726512117.113 [CommandExec](IMPORTANT): NanoDVR.baud (bit_per_second)
2024-09-16T18:41:57.119Z,1726512117.119 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none)
2024-09-16T18:41:57.120Z,1726512117.120 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none)
2024-09-16T18:41:57.120Z,1726512117.120 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second)
2024-09-16T18:41:57.240Z,1726512117.240 [CommandExec](IMPORTANT): Sonardyne_Nano.enableBroadcast (bool)
2024-09-16T18:41:57.240Z,1726512117.240 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage (volt)
2024-09-16T18:41:57.241Z,1726512117.241 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgVoltage (volt)
2024-09-16T18:41:57.241Z,1726512117.241 [CommandExec](IMPORTANT): Sonardyne_Nano.component_current (milliampere)
2024-09-16T18:41:57.242Z,1726512117.242 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent (milliampere)
2024-09-16T18:41:57.242Z,1726512117.242 [CommandExec](IMPORTANT): Sonardyne_Nano.component_discreteIn (bool)
2024-09-16T18:41:57.243Z,1726512117.243 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent (percent)
2024-09-16T18:42:04.492Z,1726512124.492 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2024-09-16T18:42:04.493Z,1726512124.493 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 100.000000 %
2024-09-16T18:43:58.963Z,1726512238.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:43:58.984Z,1726512238.984 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:43:58.984Z,1726512238.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:43:58.984Z,1726512238.984 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:43:59.367Z,1726512239.367 [Default:CheckIn:D] Stopped
2024-09-16T18:43:59.367Z,1726512239.367 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:43:59.767Z,1726512239.767 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.923385 min
2024-09-16T18:43:59.767Z,1726512239.767 [Default:CheckIn:E] Stopped
2024-09-16T18:43:59.767Z,1726512239.767 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:43:59.767Z,1726512239.767 [Default:CheckIn] Stopped
2024-09-16T18:43:59.767Z,1726512239.767 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:43:59.768Z,1726512239.768 [Default:CheckIn](INFO): Running loop #6
2024-09-16T18:43:59.768Z,1726512239.768 [Default:CheckIn] Running Loop=6
2024-09-16T18:43:59.768Z,1726512239.768 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:43:59.768Z,1726512239.768 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:44:01.777Z,1726512241.777 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184401.00,A,3648.16382,N,12147.28511,W,0.175,154.86,160924,,,D*7D
2024-09-16T18:44:01.788Z,1726512241.788 [NAL9602](INFO): GPS fix at 20240916T184401: (36.802730, -121.788085)
2024-09-16T18:44:01.807Z,1726512241.807 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:44:01.807Z,1726512241.807 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:44:09.587Z,1726512249.587 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T180619/Courier0019.lzma
2024-09-16T18:44:10.589Z,1726512250.589 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Courier0019.lzma.bak
2024-09-16T18:44:10.589Z,1726512250.589 [DataOverHttps](INFO): SBD MOMSN=23191127
2024-09-16T18:44:26.504Z,1726512266.504 [DataOverHttps](INFO): Sending 544 bytes from file Logs/20240916T180619/Express0020.lzma
2024-09-16T18:44:27.505Z,1726512267.505 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180619/Express0020.lzma.bak
2024-09-16T18:44:27.505Z,1726512267.505 [DataOverHttps](INFO): SBD MOMSN=23191133
2024-09-16T18:44:29.264Z,1726512269.264 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:44:29.264Z,1726512269.264 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:44:29.264Z,1726512269.264 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:44:32.490Z,1726512272.490 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-16T18:44:32.563Z,1726512272.563 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-09-16T18:46:44.037Z,1726512404.037 [CommandExec](IMPORTANT): got command restart application
2024-09-16T18:46:45.039Z,1726512405.039 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-09-16T18:46:45.040Z,1726512405.040 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:45.040Z,1726512405.040 [CommandExec](INFO): Uninitializing the command executive.
2024-09-16T18:46:45.040Z,1726512405.040 [CommandExec](INFO): Uninitializing the command scheduler.
2024-09-16T18:46:45.040Z,1726512405.040 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.159Z,1726512405.159 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-09-16T18:46:45.159Z,1726512405.159 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-09-16T18:46:45.160Z,1726512405.160 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.160Z,1726512405.160 [NavChartDb](INFO): Join timeout helper Thread ID is 1157
2024-09-16T18:46:45.220Z,1726512405.220 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:45.220Z,1726512405.220 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.239Z,1726512405.239 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-09-16T18:46:45.239Z,1726512405.239 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.240Z,1726512405.240 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1158
2024-09-16T18:46:45.276Z,1726512405.276 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:45.276Z,1726512405.276 [WetLabsBB2FL](INFO): Powering down
2024-09-16T18:46:45.277Z,1726512405.277 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.280Z,1726512405.280 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-09-16T18:46:45.280Z,1726512405.280 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.280Z,1726512405.280 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1159
2024-09-16T18:46:45.791Z,1726512405.791 [CTD_Seabird](INFO): Powering down
2024-09-16T18:46:45.804Z,1726512405.804 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:45.804Z,1726512405.804 [CTD_Seabird](INFO): Powering down
2024-09-16T18:46:45.815Z,1726512405.815 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.816Z,1726512405.816 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-09-16T18:46:45.816Z,1726512405.816 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:45.816Z,1726512405.816 [Radio_Surface](INFO): Join timeout helper Thread ID is 1160
2024-09-16T18:46:45.996Z,1726512405.996 [Radio_Surface](INFO): Powering down
2024-09-16T18:46:45.996Z,1726512405.996 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:45.997Z,1726512405.997 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:46.011Z,1726512406.011 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-09-16T18:46:46.011Z,1726512406.011 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:46.012Z,1726512406.012 [Onboard](INFO): Join timeout helper Thread ID is 1161
2024-09-16T18:46:46.056Z,1726512406.056 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-09-16T18:46:48.847Z,1726512408.847 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:48.848Z,1726512408.848 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:48.860Z,1726512408.860 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-09-16T18:46:48.860Z,1726512408.860 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:48.861Z,1726512408.861 [DataOverHttps](INFO): Join timeout helper Thread ID is 1162
2024-09-16T18:46:49.068Z,1726512409.068 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:49.068Z,1726512409.068 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.069Z,1726512409.069 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-09-16T18:46:49.069Z,1726512409.069 [DAT ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.070Z,1726512409.070 [DAT](INFO): Join timeout helper Thread ID is 1163
2024-09-16T18:46:49.303Z,1726512409.303 [DAT](INFO): Powering down
2024-09-16T18:46:49.376Z,1726512409.376 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:49.376Z,1726512409.376 [DAT](INFO): Powering down
2024-09-16T18:46:49.377Z,1726512409.377 [DAT ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.395Z,1726512409.395 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-09-16T18:46:49.395Z,1726512409.395 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.407Z,1726512409.407 [BackseatComponent](INFO): Join timeout helper Thread ID is 1164
2024-09-16T18:46:49.436Z,1726512409.436 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:49.436Z,1726512409.436 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.444Z,1726512409.444 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-09-16T18:46:49.444Z,1726512409.444 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.444Z,1726512409.444 [logger](INFO): Join timeout helper Thread ID is 1165
2024-09-16T18:46:49.445Z,1726512409.445 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:49.445Z,1726512409.445 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.446Z,1726512409.446 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-09-16T18:46:49.446Z,1726512409.446 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.447Z,1726512409.447 [CommandLine](INFO): Join timeout helper Thread ID is 1166
2024-09-16T18:46:49.527Z,1726512409.527 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:49.528Z,1726512409.528 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.536Z,1726512409.536 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-09-16T18:46:49.536Z,1726512409.536 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.536Z,1726512409.536 [CommandExec](INFO): Join timeout helper Thread ID is 1167
2024-09-16T18:46:49.538Z,1726512409.538 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-09-16T18:46:49.538Z,1726512409.538 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:49.538Z,1726512409.538 [controlThread](INFO): Join timeout helper Thread ID is 1168
2024-09-16T18:46:49.823Z,1726512409.823 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T18:46:49.824Z,1726512409.824 [controlThread](DEBUG): Uninitializing ControlThread
2024-09-16T18:46:49.824Z,1726512409.824 [AHRS_M2](INFO): Powering down
2024-09-16T18:46:49.991Z,1726512409.991 [DVL_micro](INFO): Powering down
2024-09-16T18:46:49.992Z,1726512409.992 [NAL9602](INFO): Powering down
2024-09-16T18:46:49.994Z,1726512409.994 [Sonardyne_Nano](INFO): Powering down
2024-09-16T18:46:50.113Z,1726512410.113 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-09-16T18:46:50.114Z,1726512410.114 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-09-16T18:46:50.115Z,1726512410.115 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-09-16T18:46:50.115Z,1726512410.115 [MissionManager](INFO): Uninitializing Mission Default
2024-09-16T18:46:50.115Z,1726512410.115 [Default] Stopped
2024-09-16T18:46:50.115Z,1726512410.115 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-16T18:46:50.115Z,1726512410.115 [Default:B.GoToSurface] Stopped
2024-09-16T18:46:50.116Z,1726512410.116 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T18:46:50.116Z,1726512410.116 [Default:CheckIn] Stopped
2024-09-16T18:46:50.116Z,1726512410.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:46:50.116Z,1726512410.116 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:46:50.116Z,1726512410.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:46:50.119Z,1726512410.119 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-09-16T18:46:50.119Z,1726512410.119 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-09-16T18:46:50.119Z,1726512410.119 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-09-16T18:46:50.120Z,1726512410.120 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-09-16T18:46:50.120Z,1726512410.120 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-09-16T18:46:50.120Z,1726512410.120 [BuoyancyServo](INFO): Powering down
2024-09-16T18:46:50.131Z,1726512410.131 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-09-16T18:46:50.132Z,1726512410.132 [ElevatorServo](INFO): Powering down
2024-09-16T18:46:50.132Z,1726512410.132 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-09-16T18:46:50.132Z,1726512410.132 [MassServo](INFO): Powering down
2024-09-16T18:46:50.133Z,1726512410.133 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-09-16T18:46:50.133Z,1726512410.133 [RudderServo](INFO): Powering down
2024-09-16T18:46:50.134Z,1726512410.134 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-09-16T18:46:50.134Z,1726512410.134 [ThrusterHE](INFO): Powering down
2024-09-16T18:46:50.135Z,1726512410.135 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-09-16T18:46:50.136Z,1726512410.136 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-09-16T18:46:50.136Z,1726512410.136 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-09-16T18:46:50.136Z,1726512410.136 [CBIT](DEBUG): Powering off loads.
2024-09-16T18:46:50.147Z,1726512410.147 [CBIT](DEBUG): Disabling WDT.
2024-09-16T18:46:50.159Z,1726512410.159 [CBIT](DEBUG): Opening all GF detection circuits.
2024-09-16T18:46:50.160Z,1726512410.160 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.214Z,1726512410.214 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.217Z,1726512410.217 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.224Z,1726512410.224 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.230Z,1726512410.230 [DAT ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.272Z,1726512410.272 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.327Z,1726512410.327 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.333Z,1726512410.333 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.355Z,1726512410.355 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T18:46:50.427Z,1726512410.427 [logger ThreadHandler](INFO): Thread cancelled.