2025-11-08T00:07:01.865Z,1762560421.865 [Supervisor](DEBUG): Initializing supervisor.
2025-11-08T00:07:01.871Z,1762560421.871 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-11-08T00:07:01.871Z,1762560421.871 [SyncHandler](INFO): Protected caller Thread ID is 1635
2025-11-08T00:07:01.872Z,1762560421.872 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-11-08T00:07:01.873Z,1762560421.873 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-11-08T00:07:01.873Z,1762560421.873 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1636
2025-11-08T00:07:01.877Z,1762560421.877 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-11-08T00:07:01.896Z,1762560421.896 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-11-08T00:07:01.897Z,1762560421.897 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-11-08T00:07:01.898Z,1762560421.898 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1637
2025-11-08T00:07:01.902Z,1762560421.902 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-11-08T00:07:01.903Z,1762560421.903 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-11-08T00:07:01.903Z,1762560421.903 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1638
2025-11-08T00:07:01.905Z,1762560421.905 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-11-08T00:07:01.906Z,1762560421.906 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-11-08T00:07:01.907Z,1762560421.907 [logger ThreadHandler](INFO): Protected caller Thread ID is 1639
2025-11-08T00:07:01.910Z,1762560421.910 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-11-08T00:07:01.911Z,1762560421.911 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-11-08T00:07:01.912Z,1762560421.912 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-11-08T00:07:02.018Z,1762560422.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-11-08T00:07:02.019Z,1762560422.019 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-11-08T00:07:02.742Z,1762560422.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-11-08T00:07:02.743Z,1762560422.743 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-11-08T00:07:02.988Z,1762560422.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-11-08T00:07:02.988Z,1762560422.988 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-11-08T00:07:03.078Z,1762560423.078 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-11-08T00:07:03.218Z,1762560423.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-11-08T00:07:03.219Z,1762560423.219 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-11-08T00:07:03.320Z,1762560423.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-11-08T00:07:03.320Z,1762560423.320 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-11-08T00:07:03.438Z,1762560423.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-11-08T00:07:03.438Z,1762560423.438 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-11-08T00:07:03.849Z,1762560423.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-11-08T00:07:03.849Z,1762560423.849 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-11-08T00:07:04.572Z,1762560424.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-11-08T00:07:04.573Z,1762560424.573 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-11-08T00:07:04.795Z,1762560424.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-11-08T00:07:04.795Z,1762560424.795 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-11-08T00:07:04.986Z,1762560424.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-11-08T00:07:04.987Z,1762560424.987 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-11-08T00:07:05.524Z,1762560425.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-11-08T00:07:05.524Z,1762560425.524 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-11-08T00:07:05.814Z,1762560425.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-11-08T00:07:05.814Z,1762560425.814 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-11-08T00:07:06.057Z,1762560426.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-11-08T00:07:06.058Z,1762560426.058 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-11-08T00:07:06.443Z,1762560426.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-11-08T00:07:06.444Z,1762560426.444 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-11-08T00:07:06.798Z,1762560426.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-11-08T00:07:06.801Z,1762560426.801 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2025-11-08T00:07:06.801Z,1762560426.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2025-11-08T00:07:06.896Z,1762560426.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2025-11-08T00:07:07.069Z,1762560427.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg
2025-11-08T00:07:07.184Z,1762560427.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2025-11-08T00:07:07.289Z,1762560427.289 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2025-11-08T00:07:07.405Z,1762560427.405 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2025-11-08T00:07:07.501Z,1762560427.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2025-11-08T00:07:07.606Z,1762560427.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2025-11-08T00:07:07.727Z,1762560427.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2025-11-08T00:07:07.999Z,1762560427.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-11-08T00:07:08.000Z,1762560428.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2025-11-08T00:07:08.095Z,1762560428.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2025-11-08T00:07:08.197Z,1762560428.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2025-11-08T00:07:08.345Z,1762560428.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2025-11-08T00:07:08.453Z,1762560428.453 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2025-11-08T00:07:08.453Z,1762560428.453 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-11-08T00:07:08.470Z,1762560428.470 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-11-08T00:07:08.601Z,1762560428.601 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-11-08T00:07:08.602Z,1762560428.602 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-11-08T00:07:08.654Z,1762560428.654 [VerticalControl](DEBUG): Construct VerticalControl.
2025-11-08T00:07:08.713Z,1762560428.713 [VerticalControl] Loaded
2025-11-08T00:07:08.713Z,1762560428.713 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-11-08T00:07:08.716Z,1762560428.716 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-11-08T00:07:08.762Z,1762560428.762 [HorizontalControl] Loaded
2025-11-08T00:07:08.763Z,1762560428.763 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-11-08T00:07:08.765Z,1762560428.765 [SpeedControl](DEBUG): Construct SpeedControl.
2025-11-08T00:07:08.768Z,1762560428.768 [SpeedControl] Loaded
2025-11-08T00:07:08.768Z,1762560428.768 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-11-08T00:07:08.771Z,1762560428.771 [LoopControl](DEBUG): Construct LoopControl.
2025-11-08T00:07:08.771Z,1762560428.771 [LoopControl] Loaded
2025-11-08T00:07:08.772Z,1762560428.772 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-11-08T00:07:08.772Z,1762560428.772 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-11-08T00:07:08.773Z,1762560428.773 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-11-08T00:07:08.824Z,1762560428.824 [DepthRateCalculator] Loaded
2025-11-08T00:07:08.824Z,1762560428.824 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-11-08T00:07:08.829Z,1762560428.829 [PitchRateCalculator] Loaded
2025-11-08T00:07:08.829Z,1762560428.829 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-11-08T00:07:08.839Z,1762560428.839 [SpeedCalculator] Loaded
2025-11-08T00:07:08.839Z,1762560428.839 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-11-08T00:07:08.843Z,1762560428.843 [YawRateCalculator] Loaded
2025-11-08T00:07:08.843Z,1762560428.843 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-11-08T00:07:08.861Z,1762560428.861 [ElevatorOffsetCalculator] Loaded
2025-11-08T00:07:08.862Z,1762560428.862 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-11-08T00:07:08.862Z,1762560428.862 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-11-08T00:07:08.863Z,1762560428.863 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-11-08T00:07:08.984Z,1762560428.984 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-11-08T00:07:08.984Z,1762560428.984 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-11-08T00:07:09.432Z,1762560429.432 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-11-08T00:07:09.433Z,1762560429.433 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-11-08T00:07:09.962Z,1762560429.962 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-11-08T00:07:09.963Z,1762560429.963 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-11-08T00:07:10.139Z,1762560430.139 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-11-08T00:07:10.139Z,1762560430.139 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-11-08T00:07:10.654Z,1762560430.654 [AHRS_M2] Loaded
2025-11-08T00:07:10.654Z,1762560430.654 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-11-08T00:07:10.693Z,1762560430.693 [BackseatComponent] Loaded
2025-11-08T00:07:10.693Z,1762560430.693 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-11-08T00:07:10.694Z,1762560430.694 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2025-11-08T00:07:10.695Z,1762560430.695 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1727
2025-11-08T00:07:10.698Z,1762560430.698 [LcmPublisher](INFO): Full slate access is ENABLED. All requested slate values (including non-universals) will be published on LCM.
2025-11-08T00:07:10.698Z,1762560430.698 [LcmPublisher] Loaded
2025-11-08T00:07:10.698Z,1762560430.698 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2025-11-08T00:07:11.505Z,1762560431.505 [BPC1] Loaded
2025-11-08T00:07:11.505Z,1762560431.505 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-11-08T00:07:11.602Z,1762560431.602 [DAT] Loaded
2025-11-08T00:07:11.602Z,1762560431.602 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-11-08T00:07:11.603Z,1762560431.603 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0
2025-11-08T00:07:11.604Z,1762560431.604 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1728
2025-11-08T00:07:11.676Z,1762560431.676 [DataOverHttps] Loaded
2025-11-08T00:07:11.677Z,1762560431.677 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-11-08T00:07:11.678Z,1762560431.678 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4A4E0
2025-11-08T00:07:11.678Z,1762560431.678 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1729
2025-11-08T00:07:11.701Z,1762560431.701 [Depth_Keller] Loaded
2025-11-08T00:07:11.701Z,1762560431.701 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-11-08T00:07:11.706Z,1762560431.706 [DropWeight] Loaded
2025-11-08T00:07:11.706Z,1762560431.706 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-11-08T00:07:11.769Z,1762560431.769 [NAL9602] Loaded
2025-11-08T00:07:11.770Z,1762560431.770 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-11-08T00:07:11.798Z,1762560431.798 [Onboard] Loaded
2025-11-08T00:07:11.798Z,1762560431.798 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-11-08T00:07:11.799Z,1762560431.799 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A7A4E0
2025-11-08T00:07:11.800Z,1762560431.800 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1730
2025-11-08T00:07:11.813Z,1762560431.813 [Power24vConverter] Loaded
2025-11-08T00:07:11.814Z,1762560431.814 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-11-08T00:07:11.829Z,1762560431.829 [Radio_Surface] Loaded
2025-11-08T00:07:11.829Z,1762560431.829 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-11-08T00:07:11.830Z,1762560431.830 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0
2025-11-08T00:07:11.831Z,1762560431.831 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1731
2025-11-08T00:07:11.877Z,1762560431.877 [RDI_Pathfinder] Loaded
2025-11-08T00:07:11.877Z,1762560431.877 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-11-08T00:07:11.892Z,1762560431.892 [Sonardyne_Nano] Loaded
2025-11-08T00:07:11.892Z,1762560431.892 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-11-08T00:07:11.893Z,1762560431.893 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-11-08T00:07:11.893Z,1762560431.893 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-11-08T00:07:12.004Z,1762560432.004 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-11-08T00:07:12.005Z,1762560432.005 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-11-08T00:07:12.018Z,1762560432.018 [NavChart] Loaded
2025-11-08T00:07:12.018Z,1762560432.018 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-11-08T00:07:12.024Z,1762560432.024 [UniversalFixResidualReporter] Loaded
2025-11-08T00:07:12.024Z,1762560432.024 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-11-08T00:07:12.025Z,1762560432.025 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-11-08T00:07:12.025Z,1762560432.025 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-11-08T00:07:12.181Z,1762560432.181 [SBIT](DEBUG): Construct Startup Built In Test.
2025-11-08T00:07:12.189Z,1762560432.189 [SBIT] Loaded
2025-11-08T00:07:12.190Z,1762560432.190 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-11-08T00:07:12.192Z,1762560432.192 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-11-08T00:07:12.205Z,1762560432.205 [IBIT] Loaded
2025-11-08T00:07:12.205Z,1762560432.205 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-11-08T00:07:12.210Z,1762560432.210 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-11-08T00:07:12.290Z,1762560432.290 [CBIT] Loaded
2025-11-08T00:07:12.290Z,1762560432.290 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-11-08T00:07:12.322Z,1762560432.322 [GFScanner] Loaded
2025-11-08T00:07:12.322Z,1762560432.322 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-11-08T00:07:12.323Z,1762560432.323 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-11-08T00:07:12.323Z,1762560432.323 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-11-08T00:07:12.460Z,1762560432.460 [BuoyancyServo] Loaded
2025-11-08T00:07:12.460Z,1762560432.460 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-11-08T00:07:12.483Z,1762560432.483 [ElevatorServo] Loaded
2025-11-08T00:07:12.483Z,1762560432.483 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-11-08T00:07:12.504Z,1762560432.504 [MassServo] Loaded
2025-11-08T00:07:12.505Z,1762560432.505 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-11-08T00:07:12.525Z,1762560432.525 [RudderServo] Loaded
2025-11-08T00:07:12.525Z,1762560432.525 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-11-08T00:07:12.544Z,1762560432.544 [ThrusterServo] Loaded
2025-11-08T00:07:12.544Z,1762560432.544 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2025-11-08T00:07:12.545Z,1762560432.545 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-11-08T00:07:12.545Z,1762560432.545 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-11-08T00:07:12.555Z,1762560432.555 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-11-08T00:07:12.556Z,1762560432.556 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-11-08T00:07:12.730Z,1762560432.730 [CTD_Seabird] Loaded
2025-11-08T00:07:12.730Z,1762560432.730 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-11-08T00:07:12.731Z,1762560432.731 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0
2025-11-08T00:07:12.731Z,1762560432.731 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1732
2025-11-08T00:07:12.754Z,1762560432.754 [PAR_Licor] Loaded
2025-11-08T00:07:12.754Z,1762560432.754 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-11-08T00:07:12.798Z,1762560432.798 [WetLabsBB2FL] Loaded
2025-11-08T00:07:12.798Z,1762560432.798 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-11-08T00:07:12.799Z,1762560432.799 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-11-08T00:07:12.799Z,1762560432.799 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1733
2025-11-08T00:07:12.820Z,1762560432.820 [WetLabsUBAT] Loaded
2025-11-08T00:07:12.820Z,1762560432.820 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2025-11-08T00:07:12.821Z,1762560432.821 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-11-08T00:07:12.821Z,1762560432.821 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1734
2025-11-08T00:07:12.822Z,1762560432.822 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-11-08T00:07:12.828Z,1762560432.828 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-11-08T00:07:12.832Z,1762560432.832 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-11-08T00:07:12.843Z,1762560432.843 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-11-08T00:07:12.844Z,1762560432.844 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9D4E0
2025-11-08T00:07:12.844Z,1762560432.844 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1735
2025-11-08T00:07:12.848Z,1762560432.848 [Supervisor](INFO): Main Thread ID is 1019
2025-11-08T00:07:12.849Z,1762560432.849 [Supervisor](DEBUG): Running supervisor.
2025-11-08T00:07:12.849Z,1762560432.849 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1736
2025-11-08T00:07:12.850Z,1762560432.850 [CommandExec](INFO): Initializing the command executive.
2025-11-08T00:07:12.852Z,1762560432.852 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1737
2025-11-08T00:07:12.855Z,1762560432.855 [controlThread ThreadHandler](INFO): Handler Thread ID is 1738
2025-11-08T00:07:12.855Z,1762560432.855 [controlThread](DEBUG): Initializing ControlThread
2025-11-08T00:07:12.856Z,1762560432.856 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-11-08T00:07:12.858Z,1762560432.858 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-11-08T00:07:12.858Z,1762560432.858 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-11-08T00:07:12.859Z,1762560432.859 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-11-08T00:07:12.859Z,1762560432.859 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-11-08T00:07:12.860Z,1762560432.860 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-11-08T00:07:12.860Z,1762560432.860 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-11-08T00:07:12.860Z,1762560432.860 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-11-08T00:07:12.861Z,1762560432.861 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-11-08T00:07:12.865Z,1762560432.865 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-11-08T00:07:12.865Z,1762560432.865 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-11-08T00:07:12.866Z,1762560432.866 [SBIT](INFO): Initialize SBIT Component.
2025-11-08T00:07:12.866Z,1762560432.866 [SBIT](IMPORTANT): git: 2025-11-06-4-g76494056e
2025-11-08T00:07:12.866Z,1762560432.866 [SBIT](INFO): git hash: 76494056ed9c20a4ea114d534fa322c9d45bc993
2025-11-08T00:07:12.867Z,1762560432.867 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-11-08T00:07:12.868Z,1762560432.868 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-11-08T00:07:12.869Z,1762560432.869 [SBIT](INFO): Beginning SBIT in 75.000000 seconds.
2025-11-08T00:07:12.869Z,1762560432.869 [IBIT](INFO): Initialize IBIT Component.
2025-11-08T00:07:12.870Z,1762560432.870 [CBIT](DEBUG): Initialize CBIT Component.
2025-11-08T00:07:12.871Z,1762560432.871 [logger ThreadHandler](INFO): Handler Thread ID is 1739
2025-11-08T00:07:12.884Z,1762560432.884 [CBIT](DEBUG): Initialized mux pins.
2025-11-08T00:07:12.884Z,1762560432.884 [CBIT](DEBUG): Initializing the watchdog timer.
2025-11-08T00:07:12.892Z,1762560432.892 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1740
2025-11-08T00:07:12.908Z,1762560432.908 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-11-08T00:07:12.908Z,1762560432.908 [CBIT](DEBUG): Initializing heartbeat.
2025-11-08T00:07:12.909Z,1762560432.909 [DAT ThreadHandler](INFO): Handler Thread ID is 1741
2025-11-08T00:07:12.909Z,1762560432.909 [DAT](INFO): Powering up
2025-11-08T00:07:12.910Z,1762560432.910 [DAT](DEBUG): Initializing DAT.
2025-11-08T00:07:12.913Z,1762560432.913 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1743
2025-11-08T00:07:12.915Z,1762560432.915 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-11-08T00:07:12.936Z,1762560432.936 [Onboard ThreadHandler](INFO): Handler Thread ID is 1744
2025-11-08T00:07:12.954Z,1762560432.954 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1745
2025-11-08T00:07:12.974Z,1762560432.974 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1746
2025-11-08T00:07:12.974Z,1762560432.974 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-11-08T00:07:12.980Z,1762560432.980 [CBIT](DEBUG): Deactivating emergency mode.
2025-11-08T00:07:12.981Z,1762560432.981 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1748
2025-11-08T00:07:12.984Z,1762560432.984 [WetLabsBB2FL](INFO): Powering up
2025-11-08T00:07:12.985Z,1762560432.985 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1750
2025-11-08T00:07:12.988Z,1762560432.988 [WetLabsUBAT](INFO): Powering up
2025-11-08T00:07:12.989Z,1762560432.989 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1752
2025-11-08T00:07:12.994Z,1762560432.994 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-11-08T00:07:12.994Z,1762560432.994 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-11-08T00:07:12.995Z,1762560432.995 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-11-08T00:07:12.995Z,1762560432.995 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-11-08T00:07:12.995Z,1762560432.995 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-11-08T00:07:12.995Z,1762560432.995 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-11-08T00:07:12.995Z,1762560432.995 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-11-08T00:07:12.995Z,1762560432.995 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-11-08T00:07:13.020Z,1762560433.020 [CBIT](DEBUG): Backplane powered.
2025-11-08T00:07:13.020Z,1762560433.020 [GFScanner](DEBUG): Initializing GFScanner
2025-11-08T00:07:13.020Z,1762560433.020 [GFScanner](DEBUG): Deactivating GF circuits.
2025-11-08T00:07:13.028Z,1762560433.028 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-11-08T00:07:13.029Z,1762560433.029 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-11-08T00:07:13.029Z,1762560433.029 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-11-08T00:07:13.030Z,1762560433.030 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-11-08T00:07:13.049Z,1762560433.049 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
2025-11-08T00:07:13.049Z,1762560433.049 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-11-08T00:07:13.050Z,1762560433.050 [MissionManager](ERROR): Could not load startup mission.
2025-11-08T00:07:13.050Z,1762560433.050 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2025-11-08T00:07:13.050Z,1762560433.050 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2025-11-08T00:07:13.060Z,1762560433.060 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2025-11-08T00:07:13.360Z,1762560433.360 [Radio_Surface](INFO): Powering up
2025-11-08T00:07:13.648Z,1762560433.648 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
0
6
10
1
3
30
Timed out while trying to detach from dock at current depth of
.
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-11-08T00:07:13.663Z,1762560433.663 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-11-08T00:07:13.675Z,1762560433.675 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h
2025-11-08T00:07:13.679Z,1762560433.679 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2025-11-08T00:07:13.699Z,1762560433.699 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s
2025-11-08T00:07:13.702Z,1762560433.702 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2025-11-08T00:07:13.718Z,1762560433.718 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min
2025-11-08T00:07:13.719Z,1762560433.719 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2025-11-08T00:07:13.875Z,1762560433.875 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2025-11-08T00:07:13.875Z,1762560433.875 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-11-08T00:07:13.931Z,1762560433.931 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-11-08T00:07:13.994Z,1762560433.994 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-11-08T00:07:13.996Z,1762560433.996 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-11-08T00:07:14.022Z,1762560434.022 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2025-11-08T00:07:14.062Z,1762560434.062 [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 DefaultWithUndock {
arguments {
ElapsedSinceDefaultStarted = 0 minute
DetachTimeout = 6 hour
"""
Maximum time duration for the vehicle to detach form the dock.
"""
TransponderCode = Dock:Dock.transponderCode
"""
Dock transponder address.
"""
TrackingUpdatePeriod = 10 second
"""
Time duration between acoustic range queries while the mission is
detaching from the dock.
"""
NumberOfPings = 1 count
"""
Number of pings requested each time.
"""
DriftTimeout = 3 minute
"""
How long to allow vehicle to drift away from dock before retrying reverse
"""
}
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 = 30 second
}
aggregate LeaveDock {
run in sequence
timeout duration=DetachTimeout {
syslog important "Timed out while trying to detach from dock at current depth of "
+ Universal:depth~meter + "."
}
# Acoustic tracking directives
behavior Estimation:TrackAcousticContact {
run while (
Universal:depth > Control:VerticalControl.surfaceThreshold
)
set contactLabelSetting = TransponderCode
set numberOfSamplesSetting = NumberOfPings
set updatePeriodSetting = TrackingUpdatePeriod
}
# Leave the dock
behavior Dock:Undock {
run in sequence
set driftTimeout = DriftTimeout
}
}
aggregate SurfaceDefault {
run in sequence
# This mission assumes open water so from here on it's the same old default that's always used
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=P2H {
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"
}
}
2025-11-08T00:07:14.067Z,1762560434.067 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2025-11-08T00:07:14.104Z,1762560434.104 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-11-08T00:07:14.169Z,1762560434.169 [Depth_Keller](INFO): Initializing.
2025-11-08T00:07:14.171Z,1762560434.171 [Power24vConverter](INFO): Powering up.
2025-11-08T00:07:14.172Z,1762560434.172 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-11-08T00:07:14.173Z,1762560434.173 [Sonardyne_Nano](INFO): Initializing.
2025-11-08T00:07:14.212Z,1762560434.212 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-11-08T00:07:14.239Z,1762560434.239 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-11-08T00:07:14.244Z,1762560434.244 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-11-08T00:07:14.245Z,1762560434.245 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-11-08T00:07:14.252Z,1762560434.252 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-11-08T00:07:14.253Z,1762560434.253 [MassServo](DEBUG): Initializing EZServoServo.
2025-11-08T00:07:14.260Z,1762560434.260 [MassServo](DEBUG): Initializing MassServo.
2025-11-08T00:07:14.261Z,1762560434.261 [RudderServo](DEBUG): Initializing EZServoServo.
2025-11-08T00:07:14.268Z,1762560434.268 [RudderServo](DEBUG): Initializing RudderServo.
2025-11-08T00:07:14.269Z,1762560434.269 [ThrusterServo](DEBUG): Initializing EZServoServo.
2025-11-08T00:07:14.276Z,1762560434.276 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2025-11-08T00:07:14.509Z,1762560434.509 [Depth_Keller](ERROR): Pressure or depth reading out of range: 824.648376 decibar, 0.000000 m
2025-11-08T00:07:14.509Z,1762560434.509 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-11-08T00:07:14.509Z,1762560434.509 [DropWeight] Hardware Fault, FailCount= 1
2025-11-08T00:07:14.510Z,1762560434.510 [DropWeight](ERROR): Hardware Fault
2025-11-08T00:07:14.521Z,1762560434.521 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-11-08T00:07:14.530Z,1762560434.530 [CommandExec](FAULT): Scheduling is paused
2025-11-08T00:07:14.531Z,1762560434.531 [CBIT](INFO): Critical error at 20251108T000714
2025-11-08T00:07:14.531Z,1762560434.531 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-11-08T00:07:14.541Z,1762560434.541 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-11-08T00:07:14.542Z,1762560434.542 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-11-08T00:07:14.900Z,1762560434.900 [CBIT](INFO): Critical error at 20251108T000714
2025-11-08T00:07:15.596Z,1762560435.596 [WetLabsBB2FL](INFO): Powering down
2025-11-08T00:07:16.094Z,1762560436.094 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-11-08T00:07:17.384Z,1762560437.384 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2025-11-08T00:07:18.636Z,1762560438.636 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-11-08T00:07:18.637Z,1762560438.637 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-11-08T00:07:22.236Z,1762560442.236 [WetLabsUBAT](INFO): Powering down
2025-11-08T00:07:25.269Z,1762560445.269 [DAT](INFO): DAT read:
2025-11-08T00:07:25.270Z,1762560445.270 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-11-08T00:07:30.813Z,1762560450.813 [DAT](INFO): DAT read: MF Frequency Band
2025-11-08T00:07:30.815Z,1762560450.815 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2025-11-08T00:07:30.816Z,1762560450.816 [DAT](INFO): DAT read: Nov 8 2025 00:07:21
2025-11-08T00:07:32.073Z,1762560452.073 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-11-08T00:07:32.074Z,1762560452.074 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-11-08T00:07:32.074Z,1762560452.074 [DAT](INFO): commRate: 600
2025-11-08T00:07:34.140Z,1762560454.140 [DAT](INFO): entering command mode
2025-11-08T00:07:34.340Z,1762560454.340 [DAT](INFO): DAT read:
2025-11-08T00:07:34.341Z,1762560454.341 [DAT](INFO): DAT read: user:1>
2025-11-08T00:07:34.341Z,1762560454.341 [DAT](INFO): setting verbose to 3
2025-11-08T00:07:34.593Z,1762560454.593 [DAT](INFO): DAT read: user:1>
2025-11-08T00:07:34.594Z,1762560454.594 [DAT](INFO): DAT read: Verbose | 3
2025-11-08T00:07:34.594Z,1762560454.594 [DAT](INFO): set verbose to 3
2025-11-08T00:07:34.595Z,1762560454.595 [DAT](INFO): setting DatVerbose to 27440
2025-11-08T00:07:34.845Z,1762560454.845 [DAT](INFO): DAT read: user:2>
2025-11-08T00:07:34.846Z,1762560454.846 [DAT](INFO): DAT read: DatVerbose | 27440
2025-11-08T00:07:34.847Z,1762560454.847 [DAT](INFO): set DatVerbose to 27440
2025-11-08T00:07:34.847Z,1762560454.847 [DAT](INFO): setting transmit power to 8
2025-11-08T00:07:35.096Z,1762560455.096 [DAT](INFO): DAT read: user:3>
2025-11-08T00:07:35.097Z,1762560455.097 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-11-08T00:07:35.098Z,1762560455.098 [DAT](INFO): set transmit power to 8
2025-11-08T00:07:35.098Z,1762560455.098 [DAT](INFO): setting local address to 10
2025-11-08T00:07:35.348Z,1762560455.348 [DAT](INFO): DAT read: user:4>
2025-11-08T00:07:35.349Z,1762560455.349 [DAT](INFO): DAT read: LocalAddr | 10
2025-11-08T00:07:35.350Z,1762560455.350 [DAT](INFO): set local address to 10
2025-11-08T00:07:35.351Z,1762560455.351 [DAT](INFO): Setting time to: 0:7:35 And date to:11/8/2025
2025-11-08T00:07:35.600Z,1762560455.600 [DAT](INFO): DAT read: user:5>
2025-11-08T00:07:35.601Z,1762560455.601 [DAT](INFO): DAT read: Sat Nov 8, 2025 00:07:35
2025-11-08T00:07:35.602Z,1762560455.602 [DAT](INFO): Local DAT time set to Sat Nov 8, 2025 00:07:35
2025-11-08T00:07:42.041Z,1762560462.041 [NAL9602](INFO): Powering up NAL9602
2025-11-08T00:07:44.083Z,1762560464.083 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:07:52.949Z,1762560472.949 [NAL9602](INFO): NAL9602 initialized
2025-11-08T00:08:15.208Z,1762560495.208 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:08:28.524Z,1762560508.524 [SBIT](IMPORTANT): Beginning Startup BIT
2025-11-08T00:08:28.529Z,1762560508.529 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-11-08T00:08:32.322Z,1762560512.322 [DAT](INFO): DAT read: user:6>Rx Time:00:08:31.5249
2025-11-08T00:08:32.322Z,1762560512.322 [DAT](INFO): Rx dataTimestamp_ set to:1762560512.321879
2025-11-08T00:08:33.065Z,1762560513.065 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000262
CHAN A1 (24V): -0.014709
CHAN A2 (12V): -0.002171
CHAN A3 (5V): -0.002860
CHAN B0 (3.3V): -0.000219
CHAN B1 (3.15aV): -0.000549
CHAN B2 (3.15bV): -0.000741
CHAN B3 (GND): 0.000111
OPEN: 0.005083
Full Scale: +/- 1 mA
2025-11-08T00:08:33.580Z,1762560513.580 [DAT](INFO): DAT read:
2025-11-08T00:08:33.582Z,1762560513.582 [DAT](INFO): DAT read: DATA(0038):set _.others_observations string "NaN"
2025-11-08T00:08:33.582Z,1762560513.582 [DAT](INFO): Got DATA 38
2025-11-08T00:08:33.583Z,1762560513.583 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:08:33.583Z,1762560513.583 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:08:33.584Z,1762560513.584 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:08:33.585Z,1762560513.585 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:18.3 AGC:14 SPD:+0.0 CCERR:012
2025-11-08T00:08:33.585Z,1762560513.585 [DAT](INFO): Got CRC:Pass
2025-11-08T00:08:33.585Z,1762560513.585 [DAT](INFO): Got CRC:Pass
2025-11-08T00:08:33.585Z,1762560513.585 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:08:33.585Z,1762560513.585 [DAT](INFO): Received command: set _.others_observations string "NaN"
2025-11-08T00:08:33.586Z,1762560513.586 [DAT](INFO): #Outgoing data=1
2025-11-08T00:08:33.586Z,1762560513.586 [DAT](INFO): Sending ack
2025-11-08T00:08:33.586Z,1762560513.586 [DAT](INFO): DAT read:
2025-11-08T00:08:33.586Z,1762560513.586 [DAT](INFO): DAT read:
2025-11-08T00:08:33.587Z,1762560513.587 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-08T00:08:33.832Z,1762560513.832 [DAT](INFO): setting remote address to 1
2025-11-08T00:08:34.085Z,1762560514.085 [DAT](INFO): DAT read:
2025-11-08T00:08:34.086Z,1762560514.086 [DAT](INFO): DAT read: RemoteAddr | 1
2025-11-08T00:08:34.086Z,1762560514.086 [DAT](INFO): set remote address to 1
2025-11-08T00:08:34.086Z,1762560514.086 [DAT](INFO): entering online mode
2025-11-08T00:08:34.337Z,1762560514.337 [DAT](INFO): DAT read: user:7>
2025-11-08T00:08:34.338Z,1762560514.338 [DAT](INFO): DAT read:
2025-11-08T00:08:34.339Z,1762560514.339 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-11-08T00:08:34.339Z,1762560514.339 [DAT](INFO): commRate: 600
2025-11-08T00:08:34.339Z,1762560514.339 [DAT](INFO): online mode acknowledged
2025-11-08T00:08:34.340Z,1762560514.340 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-08T00:08:37.114Z,1762560517.114 [CommandExec](IMPORTANT): got command set _.others_observations string "NaN"
2025-11-08T00:08:37.114Z,1762560517.114 [CommandExec](FAULT): Unable to set non-existent variable: _.others_observations
2025-11-08T00:08:37.621Z,1762560517.621 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:08:37.2068
2025-11-08T00:08:37.621Z,1762560517.621 [DAT](DEBUG): In parseResponses, sent ack so set commsState_ = SENDING_VERIFIED
2025-11-08T00:08:37.622Z,1762560517.622 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2025-11-08T00:08:43.921Z,1762560523.921 [DAT](INFO): DAT read: Rx Time:00:08:43.1250
2025-11-08T00:08:43.921Z,1762560523.921 [DAT](INFO): Rx dataTimestamp_ set to:1762560523.921022
2025-11-08T00:08:45.184Z,1762560525.184 [DAT](INFO): DAT read:
2025-11-08T00:08:45.186Z,1762560525.186 [DAT](INFO): DAT read: DATA(0038):set _.others_observations string "NaN"
2025-11-08T00:08:45.186Z,1762560525.186 [DAT](INFO): Got DATA 38
2025-11-08T00:08:45.187Z,1762560525.187 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:08:45.187Z,1762560525.187 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:08:45.188Z,1762560525.188 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:08:45.189Z,1762560525.189 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:18.3 AGC:15 SPD:+0.0 CCERR:013
2025-11-08T00:08:45.189Z,1762560525.189 [DAT](INFO): Got CRC:Pass
2025-11-08T00:08:45.189Z,1762560525.189 [DAT](INFO): Got CRC:Pass
2025-11-08T00:08:45.189Z,1762560525.189 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:08:45.189Z,1762560525.189 [DAT](INFO): Received command: set _.others_observations string "NaN"
2025-11-08T00:08:45.190Z,1762560525.190 [DAT](INFO): Sending ack
2025-11-08T00:08:45.190Z,1762560525.190 [DAT](INFO): DAT read:
2025-11-08T00:08:45.191Z,1762560525.191 [DAT](INFO): DAT read:
2025-11-08T00:08:46.340Z,1762560526.340 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:08:48.469Z,1762560528.469 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:08:48.0569
2025-11-08T00:08:48.576Z,1762560528.576 [CommandExec](IMPORTANT): got command set _.others_observations string "NaN"
2025-11-08T00:08:48.576Z,1762560528.576 [CommandExec](FAULT): Unable to set non-existent variable: _.others_observations
2025-11-08T00:08:54.773Z,1762560534.773 [DAT](INFO): DAT read: Rx Time:00:08:53.9750
2025-11-08T00:08:54.773Z,1762560534.773 [DAT](INFO): Rx dataTimestamp_ set to:1762560534.772888
2025-11-08T00:08:56.032Z,1762560536.032 [DAT](INFO): DAT read:
2025-11-08T00:08:56.034Z,1762560536.034 [DAT](INFO): DAT read: DATA(0038):set _.others_observations string "NaN"
2025-11-08T00:08:56.034Z,1762560536.034 [DAT](INFO): Got DATA 38
2025-11-08T00:08:56.035Z,1762560536.035 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:08:56.035Z,1762560536.035 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:08:56.036Z,1762560536.036 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:08:56.037Z,1762560536.037 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:18.2 AGC:15 SPD:+0.0 CCERR:013
2025-11-08T00:08:56.037Z,1762560536.037 [DAT](INFO): Got CRC:Pass
2025-11-08T00:08:56.037Z,1762560536.037 [DAT](INFO): Got CRC:Pass
2025-11-08T00:08:56.037Z,1762560536.037 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:08:56.037Z,1762560536.037 [DAT](INFO): Received command: set _.others_observations string "NaN"
2025-11-08T00:08:56.038Z,1762560536.038 [DAT](INFO): Sending ack
2025-11-08T00:08:56.038Z,1762560536.038 [DAT](INFO): DAT read:
2025-11-08T00:08:56.038Z,1762560536.038 [DAT](INFO): DAT read:
2025-11-08T00:08:59.091Z,1762560539.091 [CommandExec](IMPORTANT): got command set _.others_observations string "NaN"
2025-11-08T00:08:59.091Z,1762560539.091 [CommandExec](FAULT): Unable to set non-existent variable: _.others_observations
2025-11-08T00:08:59.309Z,1762560539.309 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:08:58.9070
2025-11-08T00:09:05.865Z,1762560545.865 [DAT](INFO): DAT read: Rx Time:00:09:04.8250
2025-11-08T00:09:05.866Z,1762560545.866 [DAT](INFO): Rx dataTimestamp_ set to:1762560545.865388
2025-11-08T00:09:06.872Z,1762560546.872 [DAT](INFO): DAT read:
2025-11-08T00:09:06.874Z,1762560546.874 [DAT](INFO): DAT read: DATA(0035):set _.others_observations string ""
2025-11-08T00:09:06.874Z,1762560546.874 [DAT](INFO): Got DATA 35
2025-11-08T00:09:06.875Z,1762560546.875 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:09:06.875Z,1762560546.875 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:09:06.875Z,1762560546.875 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:09:06.877Z,1762560546.877 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:17.7 AGC:15 SPD:+0.0 CCERR:012
2025-11-08T00:09:06.877Z,1762560546.877 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:06.877Z,1762560546.877 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:06.877Z,1762560546.877 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:09:06.877Z,1762560546.877 [DAT](INFO): Received command: set _.others_observations string ""
2025-11-08T00:09:06.877Z,1762560546.877 [DAT](INFO): Sending ack
2025-11-08T00:09:06.878Z,1762560546.878 [DAT](INFO): DAT read:
2025-11-08T00:09:06.878Z,1762560546.878 [DAT](INFO): DAT read:
2025-11-08T00:09:09.847Z,1762560549.847 [CommandExec](IMPORTANT): got command set _.others_observations string ""
2025-11-08T00:09:09.848Z,1762560549.848 [CommandExec](IMPORTANT): NO CODE YET -- parsed command -- name[ _.others_observations ] binaryType[ 11 ] blobType[ 0 ]
2025-11-08T00:09:09.849Z,1762560549.849 [CommandExec](FAULT): No value provided to set
2025-11-08T00:09:10.153Z,1762560550.153 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:09:09.7571
2025-11-08T00:09:16.705Z,1762560556.705 [DAT](INFO): DAT read: Rx Time:00:09:15.6750
2025-11-08T00:09:16.705Z,1762560556.705 [DAT](INFO): Rx dataTimestamp_ set to:1762560556.704951
2025-11-08T00:09:17.464Z,1762560557.464 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:09:17.714Z,1762560557.714 [DAT](INFO): DAT read:
2025-11-08T00:09:17.716Z,1762560557.716 [DAT](INFO): DAT read: DATA(0035):set _.others_observations string ""
2025-11-08T00:09:17.717Z,1762560557.717 [DAT](INFO): Got DATA 35
2025-11-08T00:09:17.718Z,1762560557.718 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:09:17.718Z,1762560557.718 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:09:17.718Z,1762560557.718 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:09:17.719Z,1762560557.719 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:17.8 AGC:15 SPD:+0.0 CCERR:012
2025-11-08T00:09:17.719Z,1762560557.719 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:17.720Z,1762560557.720 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:17.720Z,1762560557.720 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:09:17.720Z,1762560557.720 [DAT](INFO): Received command: set _.others_observations string ""
2025-11-08T00:09:17.720Z,1762560557.720 [DAT](INFO): Sending ack
2025-11-08T00:09:17.721Z,1762560557.721 [DAT](INFO): DAT read:
2025-11-08T00:09:17.721Z,1762560557.721 [DAT](INFO): DAT read:
2025-11-08T00:09:20.452Z,1762560560.452 [CommandExec](IMPORTANT): got command set _.others_observations string ""
2025-11-08T00:09:20.453Z,1762560560.453 [CommandExec](FAULT): No value provided to set
2025-11-08T00:09:20.989Z,1762560560.989 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:09:20.6072
2025-11-08T00:09:21.000Z,1762560562.000 [SBIT](IMPORTANT): SBIT PASSED
2025-11-08T00:09:21.000Z,1762560562.000 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-11-08T00:09:22.000Z,1762560562.000 [SBIT](IMPORTANT): BackseatComponent.fullSlateAccess=1 bool;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): CBIT.abortDepth=300 meter;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): CBIT.stopDepth=290 meter;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): DAT.surfaceThreshold=-0.5 meter;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): Docked.verbose=0 bool;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): DockingStepper.deviationValue=250 count;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=0 bool;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;
2025-11-08T00:09:22.001Z,1762560562.001 [SBIT](IMPORTANT): Express mostRecent WetLabsUBAT.average_bioluminescence;
2025-11-08T00:09:22.002Z,1762560562.002 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second;
2025-11-08T00:09:22.002Z,1762560562.002 [SBIT](IMPORTANT): Normal linearApproximation _.dinoflagellates 10000000000.000000 none;
2025-11-08T00:09:22.002Z,1762560562.002 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=521.144203 cubic_centimeter;
2025-11-08T00:09:22.002Z,1762560562.002 [SBIT](IMPORTANT): VerticalControl.massDefault=13.994444 millimeter;
2025-11-08T00:09:22.389Z,1762560562.389 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-11-08T00:09:22.389Z,1762560562.389 [DefaultWithUndock] Running Loop=1
2025-11-08T00:09:22.389Z,1762560562.389 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-11-08T00:09:22.390Z,1762560562.390 [DefaultWithUndock:A.Wait] Running Loop=1
2025-11-08T00:09:22.390Z,1762560562.390 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-11-08T00:09:27.293Z,1762560567.293 [DAT](INFO): DAT read: Rx Time:00:09:26.4752
2025-11-08T00:09:27.293Z,1762560567.293 [DAT](INFO): Rx dataTimestamp_ set to:1762560567.292854
2025-11-08T00:09:28.552Z,1762560568.552 [DAT](INFO): DAT read:
2025-11-08T00:09:28.554Z,1762560568.554 [DAT](INFO): DAT read: DATA(0035):set _.others_observations string ""
2025-11-08T00:09:28.554Z,1762560568.554 [DAT](INFO): Got DATA 35
2025-11-08T00:09:28.555Z,1762560568.555 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:09:28.555Z,1762560568.555 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:09:28.556Z,1762560568.556 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:09:28.557Z,1762560568.557 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:17.7 AGC:15 SPD:+0.0 CCERR:012
2025-11-08T00:09:28.557Z,1762560568.557 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:28.557Z,1762560568.557 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:28.557Z,1762560568.557 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:09:28.557Z,1762560568.557 [DAT](INFO): Received command: set _.others_observations string ""
2025-11-08T00:09:28.558Z,1762560568.558 [DAT](INFO): Sending ack
2025-11-08T00:09:28.558Z,1762560568.558 [DAT](INFO): DAT read:
2025-11-08T00:09:28.558Z,1762560568.558 [DAT](INFO): DAT read:
2025-11-08T00:09:29.230Z,1762560569.230 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-11-08T00:09:31.286Z,1762560571.286 [CommandExec](IMPORTANT): got command set _.others_observations string ""
2025-11-08T00:09:31.286Z,1762560571.286 [CommandExec](FAULT): No value provided to set
2025-11-08T00:09:31.829Z,1762560571.829 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:09:31.4573
2025-11-08T00:09:38.133Z,1762560578.133 [DAT](INFO): DAT read: Rx Time:00:09:37.3252
2025-11-08T00:09:38.133Z,1762560578.133 [DAT](INFO): Rx dataTimestamp_ set to:1762560578.132772
2025-11-08T00:09:39.388Z,1762560579.388 [DAT](INFO): DAT read:
2025-11-08T00:09:39.390Z,1762560579.390 [DAT](INFO): DAT read: DATA(0035):set _.others_observations string ""
2025-11-08T00:09:39.390Z,1762560579.390 [DAT](INFO): Got DATA 35
2025-11-08T00:09:39.391Z,1762560579.391 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:09:39.391Z,1762560579.391 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:09:39.392Z,1762560579.392 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:09:39.393Z,1762560579.393 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:17.7 AGC:15 SPD:+0.0 CCERR:013
2025-11-08T00:09:39.393Z,1762560579.393 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:39.393Z,1762560579.393 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:39.393Z,1762560579.393 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:09:39.393Z,1762560579.393 [DAT](INFO): Received command: set _.others_observations string ""
2025-11-08T00:09:39.394Z,1762560579.394 [DAT](INFO): Sending ack
2025-11-08T00:09:39.394Z,1762560579.394 [DAT](INFO): DAT read:
2025-11-08T00:09:39.395Z,1762560579.395 [DAT](INFO): DAT read:
2025-11-08T00:09:42.128Z,1762560582.128 [CommandExec](IMPORTANT): got command set _.others_observations string ""
2025-11-08T00:09:42.129Z,1762560582.129 [CommandExec](FAULT): No value provided to set
2025-11-08T00:09:42.159Z,1762560582.159 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-11-08T00:09:42.665Z,1762560582.665 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:09:42.2574
2025-11-08T00:09:48.600Z,1762560588.600 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:09:48.969Z,1762560588.969 [DAT](INFO): DAT read: Rx Time:00:09:48.1752
2025-11-08T00:09:48.969Z,1762560588.969 [DAT](INFO): Rx dataTimestamp_ set to:1762560588.969091
2025-11-08T00:09:50.232Z,1762560590.232 [DAT](INFO): DAT read:
2025-11-08T00:09:50.234Z,1762560590.234 [DAT](INFO): DAT read: DATA(0045):set _.others_observations string "test_msg_5"
2025-11-08T00:09:50.234Z,1762560590.234 [DAT](INFO): Got DATA 45
2025-11-08T00:09:50.235Z,1762560590.235 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:09:50.235Z,1762560590.235 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:09:50.236Z,1762560590.236 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:09:50.237Z,1762560590.237 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:18.5 AGC:14 SPD:+0.0 CCERR:013
2025-11-08T00:09:50.237Z,1762560590.237 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:50.237Z,1762560590.237 [DAT](INFO): Got CRC:Pass
2025-11-08T00:09:50.237Z,1762560590.237 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:09:50.237Z,1762560590.237 [DAT](INFO): Received command: set _.others_observations string "test_msg_5"
2025-11-08T00:09:50.238Z,1762560590.238 [DAT](INFO): Sending ack
2025-11-08T00:09:50.238Z,1762560590.238 [DAT](INFO): DAT read:
2025-11-08T00:09:50.239Z,1762560590.239 [DAT](INFO): DAT read:
2025-11-08T00:09:52.685Z,1762560592.685 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-11-08T00:09:52.685Z,1762560592.685 [DefaultWithUndock:A.Wait] Stopped
2025-11-08T00:09:52.685Z,1762560592.685 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-11-08T00:09:52.686Z,1762560592.686 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-11-08T00:09:52.686Z,1762560592.686 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-11-08T00:09:52.686Z,1762560592.686 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-11-08T00:09:52.686Z,1762560592.686 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-11-08T00:09:52.686Z,1762560592.686 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-11-08T00:09:52.686Z,1762560592.686 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-11-08T00:09:52.686Z,1762560592.686 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-11-08T00:09:53.133Z,1762560593.133 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-11-08T00:09:53.133Z,1762560593.133 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-11-08T00:09:53.135Z,1762560593.135 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.238780 m.
2025-11-08T00:09:53.135Z,1762560593.135 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-11-08T00:09:53.135Z,1762560593.135 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-11-08T00:09:53.510Z,1762560593.510 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-11-08T00:09:53.517Z,1762560593.517 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:09:53.1075
2025-11-08T00:09:55.575Z,1762560595.575 [CommandExec](IMPORTANT): got command set _.others_observations string "test_msg_5"
2025-11-08T00:09:59.076Z,1762560599.076 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009015
2025-11-08T00:09:59.818Z,1762560599.818 [DAT](INFO): DAT read: Rx Time:00:09:59.0253
2025-11-08T00:09:59.818Z,1762560599.818 [DAT](INFO): Rx dataTimestamp_ set to:1762560599.818111
2025-11-08T00:10:00.080Z,1762560600.080 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:10:01.076Z,1762560601.076 [DAT](INFO): DAT read:
2025-11-08T00:10:01.078Z,1762560601.078 [DAT](INFO): DAT read: DATA(0045):set _.others_observations string "test_msg_5"
2025-11-08T00:10:01.078Z,1762560601.078 [DAT](INFO): Got DATA 45
2025-11-08T00:10:01.079Z,1762560601.079 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:10:01.083Z,1762560601.083 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:10:01.083Z,1762560601.083 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:10:01.085Z,1762560601.085 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:18.2 AGC:15 SPD:+0.0 CCERR:013
2025-11-08T00:10:01.085Z,1762560601.085 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:01.085Z,1762560601.085 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:01.085Z,1762560601.085 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:10:01.086Z,1762560601.086 [DAT](INFO): Received command: set _.others_observations string "test_msg_5"
2025-11-08T00:10:01.086Z,1762560601.086 [DAT](INFO): Sending ack
2025-11-08T00:10:01.086Z,1762560601.086 [DAT](INFO): DAT read:
2025-11-08T00:10:01.086Z,1762560601.086 [DAT](INFO): DAT read:
2025-11-08T00:10:04.353Z,1762560604.353 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:10:03.9576
2025-11-08T00:10:06.936Z,1762560606.936 [CommandExec](IMPORTANT): got command set _.others_observations string "test_msg_5"
2025-11-08T00:10:08.662Z,1762560608.662 [CommandExec](IMPORTANT): got command get _.others_observations
2025-11-08T00:10:08.663Z,1762560608.663 [CommandExec](IMPORTANT): _.others_observations 2.311699 _
2025-11-08T00:10:10.910Z,1762560610.910 [DAT](INFO): DAT read: Rx Time:00:10:09.8753
2025-11-08T00:10:10.911Z,1762560610.911 [DAT](INFO): Rx dataTimestamp_ set to:1762560610.910381
2025-11-08T00:10:11.916Z,1762560611.916 [DAT](INFO): DAT read:
2025-11-08T00:10:11.918Z,1762560611.918 [DAT](INFO): DAT read: DATA(0045):set _.others_observations string "test_msg_5"
2025-11-08T00:10:11.918Z,1762560611.918 [DAT](INFO): Got DATA 45
2025-11-08T00:10:11.919Z,1762560611.919 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:10:11.919Z,1762560611.919 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:10:11.920Z,1762560611.920 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:10:11.921Z,1762560611.921 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:18.4 AGC:15 SPD:+0.0 CCERR:013
2025-11-08T00:10:11.921Z,1762560611.921 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:11.921Z,1762560611.921 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:11.921Z,1762560611.921 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:10:11.922Z,1762560611.922 [DAT](INFO): Received command: set _.others_observations string "test_msg_5"
2025-11-08T00:10:11.922Z,1762560611.922 [DAT](INFO): Sending ack
2025-11-08T00:10:11.922Z,1762560611.922 [DAT](INFO): DAT read:
2025-11-08T00:10:11.922Z,1762560611.922 [DAT](INFO): DAT read:
2025-11-08T00:10:12.116Z,1762560612.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:10:12.892Z,1762560612.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-11-08T00:10:12.892Z,1762560612.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-11-08T00:10:12.951Z,1762560612.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-11-08T00:10:13.351Z,1762560613.351 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-11-08T00:10:13.351Z,1762560613.351 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-11-08T00:10:13.774Z,1762560613.774 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-11-08T00:10:14.097Z,1762560614.097 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module hardware isn't loaded, so simply satisfying DockingState request.
2025-11-08T00:10:14.097Z,1762560614.097 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-11-08T00:10:14.529Z,1762560614.529 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-11-08T00:10:14.529Z,1762560614.529 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-11-08T00:10:14.529Z,1762560614.529 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-11-08T00:10:14.529Z,1762560614.529 [DefaultWithUndock:LeaveDock] Stopped
2025-11-08T00:10:14.529Z,1762560614.529 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-11-08T00:10:14.529Z,1762560614.529 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-11-08T00:10:14.529Z,1762560614.529 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-11-08T00:10:14.530Z,1762560614.530 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-11-08T00:10:14.530Z,1762560614.530 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-11-08T00:10:14.530Z,1762560614.530 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-11-08T00:10:14.531Z,1762560614.531 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-11-08T00:10:14.531Z,1762560614.531 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-11-08T00:10:14.531Z,1762560614.531 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-11-08T00:10:14.580Z,1762560614.580 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-11-08T00:10:14.580Z,1762560614.580 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-11-08T00:10:14.913Z,1762560614.913 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-11-08T00:10:14.914Z,1762560614.914 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-11-08T00:10:14.914Z,1762560614.914 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-11-08T00:10:15.193Z,1762560615.193 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:10:14.8078
2025-11-08T00:10:15.313Z,1762560615.313 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-11-08T00:10:15.565Z,1762560615.565 [CommandExec](IMPORTANT): got command set _.others_observations string "test_msg_5"
2025-11-08T00:10:21.513Z,1762560621.513 [DAT](INFO): DAT read: Rx Time:00:10:20.6754
2025-11-08T00:10:21.515Z,1762560621.515 [DAT](INFO): Rx dataTimestamp_ set to:1762560621.514133
2025-11-08T00:10:21.604Z,1762560621.604 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:10:22.773Z,1762560622.773 [DAT](INFO): DAT read:
2025-11-08T00:10:22.775Z,1762560622.775 [DAT](INFO): DAT read: DATA(0045):set _.others_observations string "test_msg_5"
2025-11-08T00:10:22.775Z,1762560622.775 [DAT](INFO): Got DATA 45
2025-11-08T00:10:22.776Z,1762560622.776 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:10:22.776Z,1762560622.776 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:10:22.777Z,1762560622.777 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:10:22.778Z,1762560622.778 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:18.5 AGC:15 SPD:+0.0 CCERR:013
2025-11-08T00:10:22.778Z,1762560622.778 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:22.778Z,1762560622.778 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:22.778Z,1762560622.778 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:10:22.778Z,1762560622.778 [DAT](INFO): Received command: set _.others_observations string "test_msg_5"
2025-11-08T00:10:22.779Z,1762560622.779 [DAT](INFO): Sending ack
2025-11-08T00:10:22.779Z,1762560622.779 [DAT](INFO): DAT read:
2025-11-08T00:10:22.779Z,1762560622.779 [DAT](INFO): DAT read:
2025-11-08T00:10:26.049Z,1762560626.049 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:10:25.6579
2025-11-08T00:10:28.006Z,1762560628.006 [CommandExec](IMPORTANT): got command set _.others_observations string "test_msg_5"
2025-11-08T00:10:32.365Z,1762560632.365 [DAT](INFO): DAT read: Rx Time:00:10:31.5255
2025-11-08T00:10:32.365Z,1762560632.365 [DAT](INFO): Rx dataTimestamp_ set to:1762560632.364837
2025-11-08T00:10:32.528Z,1762560632.528 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:10:33.624Z,1762560633.624 [DAT](INFO): DAT read:
2025-11-08T00:10:33.629Z,1762560633.629 [DAT](INFO): DAT read: DATA(0046):set _.others_observations string "test_msg_13"
2025-11-08T00:10:33.629Z,1762560633.629 [DAT](INFO): Got DATA 46
2025-11-08T00:10:33.631Z,1762560633.631 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:10:33.631Z,1762560633.631 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:10:33.632Z,1762560633.632 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:10:33.633Z,1762560633.633 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:18.1 AGC:13 SPD:+0.0 CCERR:012
2025-11-08T00:10:33.633Z,1762560633.633 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:33.633Z,1762560633.633 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:33.633Z,1762560633.633 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:10:33.633Z,1762560633.633 [DAT](INFO): Received command: set _.others_observations string "test_msg_13"
2025-11-08T00:10:33.634Z,1762560633.634 [DAT](INFO): Sending ack
2025-11-08T00:10:33.634Z,1762560633.634 [DAT](INFO): DAT read:
2025-11-08T00:10:33.634Z,1762560633.634 [DAT](INFO): DAT read:
2025-11-08T00:10:36.901Z,1762560636.901 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:10:36.5080
2025-11-08T00:10:38.607Z,1762560638.607 [CommandExec](IMPORTANT): got command set _.others_observations string "test_msg_13"
2025-11-08T00:10:43.201Z,1762560643.201 [DAT](INFO): DAT read: Rx Time:00:10:42.3756
2025-11-08T00:10:43.201Z,1762560643.201 [DAT](INFO): Rx dataTimestamp_ set to:1762560643.200716
2025-11-08T00:10:43.224Z,1762560643.224 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:10:44.469Z,1762560644.469 [DAT](INFO): DAT read:
2025-11-08T00:10:44.470Z,1762560644.470 [DAT](INFO): DAT read: DATA(0046):set _.others_observations string "test_msg_13"
2025-11-08T00:10:44.471Z,1762560644.471 [DAT](INFO): Got DATA 46
2025-11-08T00:10:44.472Z,1762560644.472 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:10:44.472Z,1762560644.472 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:10:44.472Z,1762560644.472 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:10:44.473Z,1762560644.473 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:18.2 AGC:15 SPD:+0.0 CCERR:013
2025-11-08T00:10:44.473Z,1762560644.473 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:44.473Z,1762560644.473 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:44.474Z,1762560644.474 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:10:44.474Z,1762560644.474 [DAT](INFO): Received command: set _.others_observations string "test_msg_13"
2025-11-08T00:10:44.474Z,1762560644.474 [DAT](INFO): Sending ack
2025-11-08T00:10:44.474Z,1762560644.474 [DAT](INFO): DAT read:
2025-11-08T00:10:44.475Z,1762560644.475 [DAT](INFO): DAT read:
2025-11-08T00:10:47.745Z,1762560647.745 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:10:47.3581
2025-11-08T00:10:53.290Z,1762560653.290 [CommandExec](IMPORTANT): got command set _.others_observations string "test_msg_13"
2025-11-08T00:10:54.053Z,1762560654.053 [DAT](INFO): DAT read: Rx Time:00:10:53.2256
2025-11-08T00:10:54.053Z,1762560654.053 [DAT](INFO): Rx dataTimestamp_ set to:1762560654.053132
2025-11-08T00:10:55.313Z,1762560655.313 [DAT](INFO): DAT read:
2025-11-08T00:10:55.314Z,1762560655.314 [DAT](INFO): DAT read: DATA(0046):set _.others_observations string "test_msg_13"
2025-11-08T00:10:55.315Z,1762560655.315 [DAT](INFO): Got DATA 46
2025-11-08T00:10:55.316Z,1762560655.316 [DAT](INFO): DAT read: Source:001 Destination:010
2025-11-08T00:10:55.316Z,1762560655.316 [DAT](INFO): Got Src/Dest after DATA
2025-11-08T00:10:55.316Z,1762560655.316 [DAT](INFO): DATA Src=1, Dst=10
2025-11-08T00:10:55.317Z,1762560655.317 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:18.0 AGC:14 SPD:+0.0 CCERR:012
2025-11-08T00:10:55.317Z,1762560655.317 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:55.318Z,1762560655.318 [DAT](INFO): Got CRC:Pass
2025-11-08T00:10:55.318Z,1762560655.318 [DAT](INFO): Incoming data is intended for us
2025-11-08T00:10:55.318Z,1762560655.318 [DAT](INFO): Received command: set _.others_observations string "test_msg_13"
2025-11-08T00:10:55.319Z,1762560655.319 [DAT](INFO): Sending ack
2025-11-08T00:10:55.319Z,1762560655.319 [DAT](INFO): DAT read:
2025-11-08T00:10:55.320Z,1762560655.320 [DAT](INFO): DAT read:
2025-11-08T00:10:58.589Z,1762560658.589 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:10:58.2082
2025-11-08T00:10:58.828Z,1762560658.828 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:11:00.572Z,1762560660.572 [CommandExec](IMPORTANT): got command set _.others_observations string "test_msg_13"
2025-11-08T00:11:11.484Z,1762560671.484 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:11:20.088Z,1762560680.088 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:11:28.504Z,1762560688.504 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:11:37.192Z,1762560697.192 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:11:45.732Z,1762560705.732 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:11:53.232Z,1762560713.232 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:01.840Z,1762560721.840 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:10.236Z,1762560730.236 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:18.804Z,1762560738.804 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:27.460Z,1762560747.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:36.016Z,1762560756.016 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:44.524Z,1762560764.524 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:53.084Z,1762560773.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:12:55.809Z,1762560775.809 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-11-08T00:13:01.612Z,1762560781.612 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:13:10.324Z,1762560790.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:13:14.077Z,1762560794.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-11-08T00:13:14.077Z,1762560794.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-11-08T00:13:14.136Z,1762560794.136 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-11-08T00:13:14.437Z,1762560794.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-11-08T00:13:14.437Z,1762560794.437 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-11-08T00:13:18.897Z,1762560798.897 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:13:27.380Z,1762560807.380 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:13:35.000Z,1762560816.000 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:13:44.436Z,1762560824.436 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:13:53.168Z,1762560833.168 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:13:59.960Z,1762560839.960 [CommandExec](IMPORTANT): got command quit
2025-11-08T00:14:00.964Z,1762560840.964 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:00.964Z,1762560840.964 [CommandExec](INFO): Uninitializing the command executive.
2025-11-08T00:14:00.964Z,1762560840.964 [CommandExec](INFO): Uninitializing the command scheduler.
2025-11-08T00:14:00.964Z,1762560840.964 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.052Z,1762560841.052 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-11-08T00:14:01.052Z,1762560841.052 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-11-08T00:14:01.052Z,1762560841.052 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.053Z,1762560841.053 [NavChartDb](INFO): Join timeout helper Thread ID is 1790
2025-11-08T00:14:01.404Z,1762560841.404 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:01.404Z,1762560841.404 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.416Z,1762560841.416 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2025-11-08T00:14:01.416Z,1762560841.416 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.416Z,1762560841.416 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1791
2025-11-08T00:14:01.417Z,1762560841.417 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:01.417Z,1762560841.417 [WetLabsUBAT](INFO): Powering down
2025-11-08T00:14:01.418Z,1762560841.418 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.418Z,1762560841.418 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-11-08T00:14:01.418Z,1762560841.418 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.419Z,1762560841.419 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1792
2025-11-08T00:14:01.460Z,1762560841.460 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:01.460Z,1762560841.460 [WetLabsBB2FL](INFO): Powering down
2025-11-08T00:14:01.461Z,1762560841.461 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.468Z,1762560841.468 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-11-08T00:14:01.468Z,1762560841.468 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.468Z,1762560841.468 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1793
2025-11-08T00:14:01.720Z,1762560841.720 [CTD_Seabird](INFO): Powering down
2025-11-08T00:14:01.732Z,1762560841.732 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:01.732Z,1762560841.732 [CTD_Seabird](INFO): Powering down
2025-11-08T00:14:01.745Z,1762560841.745 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.746Z,1762560841.746 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-11-08T00:14:01.746Z,1762560841.746 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.746Z,1762560841.746 [Radio_Surface](INFO): Join timeout helper Thread ID is 1794
2025-11-08T00:14:01.956Z,1762560841.956 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-08T00:14:01.984Z,1762560841.984 [Radio_Surface](INFO): Powering down
2025-11-08T00:14:01.985Z,1762560841.985 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:01.985Z,1762560841.985 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.993Z,1762560841.993 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-11-08T00:14:01.993Z,1762560841.993 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:01.993Z,1762560841.993 [Onboard](INFO): Join timeout helper Thread ID is 1795
2025-11-08T00:14:03.308Z,1762560843.308 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:03.308Z,1762560843.308 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:03.321Z,1762560843.321 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-11-08T00:14:03.321Z,1762560843.321 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:03.321Z,1762560843.321 [DataOverHttps](INFO): Join timeout helper Thread ID is 1796
2025-11-08T00:14:03.964Z,1762560843.964 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:03.965Z,1762560843.965 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:03.985Z,1762560843.985 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-11-08T00:14:03.986Z,1762560843.986 [DAT ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:03.986Z,1762560843.986 [DAT](INFO): Join timeout helper Thread ID is 1797
2025-11-08T00:14:04.160Z,1762560844.160 [DAT](INFO): Powering down
2025-11-08T00:14:04.232Z,1762560844.232 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:04.233Z,1762560844.233 [DAT](INFO): Powering down
2025-11-08T00:14:04.233Z,1762560844.233 [DAT ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.253Z,1762560844.253 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-11-08T00:14:04.253Z,1762560844.253 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.253Z,1762560844.253 [BackseatComponent](INFO): Join timeout helper Thread ID is 1798
2025-11-08T00:14:04.280Z,1762560844.280 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:04.280Z,1762560844.280 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.284Z,1762560844.284 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-11-08T00:14:04.285Z,1762560844.285 [logger ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.285Z,1762560844.285 [logger](INFO): Join timeout helper Thread ID is 1799
2025-11-08T00:14:04.292Z,1762560844.292 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:04.292Z,1762560844.292 [logger ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.304Z,1762560844.304 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-11-08T00:14:04.304Z,1762560844.304 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.305Z,1762560844.305 [CommandLine](INFO): Join timeout helper Thread ID is 1800
2025-11-08T00:14:04.340Z,1762560844.340 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:04.340Z,1762560844.340 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.344Z,1762560844.344 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-11-08T00:14:04.344Z,1762560844.344 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.345Z,1762560844.345 [CommandExec](INFO): Join timeout helper Thread ID is 1801
2025-11-08T00:14:04.346Z,1762560844.346 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-11-08T00:14:04.346Z,1762560844.346 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.347Z,1762560844.347 [controlThread](INFO): Join timeout helper Thread ID is 1802
2025-11-08T00:14:04.476Z,1762560844.476 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-08T00:14:04.477Z,1762560844.477 [controlThread](DEBUG): Uninitializing ControlThread
2025-11-08T00:14:04.477Z,1762560844.477 [AHRS_M2](INFO): Powering down
2025-11-08T00:14:04.556Z,1762560844.556 [NAL9602](INFO): Powering down
2025-11-08T00:14:04.628Z,1762560844.628 [RDI_Pathfinder](INFO): Powering down
2025-11-08T00:14:04.629Z,1762560844.629 [Sonardyne_Nano](INFO): Powering down
2025-11-08T00:14:04.756Z,1762560844.756 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-11-08T00:14:04.757Z,1762560844.757 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-11-08T00:14:04.757Z,1762560844.757 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-11-08T00:14:04.758Z,1762560844.758 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock] Stopped
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock:SurfaceDefault] Stopped
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-11-08T00:14:04.758Z,1762560844.758 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-11-08T00:14:04.759Z,1762560844.759 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-11-08T00:14:04.767Z,1762560844.767 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-11-08T00:14:04.768Z,1762560844.768 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-11-08T00:14:04.768Z,1762560844.768 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-11-08T00:14:04.768Z,1762560844.768 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-11-08T00:14:04.768Z,1762560844.768 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-11-08T00:14:04.769Z,1762560844.769 [BuoyancyServo](INFO): Powering down
2025-11-08T00:14:04.780Z,1762560844.780 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-11-08T00:14:04.780Z,1762560844.780 [ElevatorServo](INFO): Powering down
2025-11-08T00:14:04.781Z,1762560844.781 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-11-08T00:14:04.781Z,1762560844.781 [MassServo](INFO): Powering down
2025-11-08T00:14:04.782Z,1762560844.782 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-11-08T00:14:04.782Z,1762560844.782 [RudderServo](INFO): Powering down
2025-11-08T00:14:04.783Z,1762560844.783 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2025-11-08T00:14:04.783Z,1762560844.783 [ThrusterServo](INFO): Powering down
2025-11-08T00:14:04.784Z,1762560844.784 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-11-08T00:14:04.784Z,1762560844.784 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-11-08T00:14:04.784Z,1762560844.784 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-11-08T00:14:04.785Z,1762560844.785 [CBIT](DEBUG): Powering off loads.
2025-11-08T00:14:04.795Z,1762560844.795 [CBIT](DEBUG): Disabling WDT.
2025-11-08T00:14:04.808Z,1762560844.808 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-11-08T00:14:04.808Z,1762560844.808 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-11-08T00:14:04.809Z,1762560844.809 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.874Z,1762560844.874 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.877Z,1762560844.877 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.883Z,1762560844.883 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.890Z,1762560844.890 [DAT ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.932Z,1762560844.932 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.966Z,1762560844.966 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-11-08T00:14:04.967Z,1762560844.967 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-11-08T00:14:04.995Z,1762560844.995 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:04.997Z,1762560844.997 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:05.001Z,1762560845.001 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:05.009Z,1762560845.009 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-11-08T00:14:05.077Z,1762560845.077 [logger ThreadHandler](INFO): Thread cancelled.