2025-07-03T01:46:13.712Z,1751507173.712 [Supervisor](DEBUG): Initializing supervisor. 2025-07-03T01:46:13.716Z,1751507173.716 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-07-03T01:46:13.717Z,1751507173.717 [SyncHandler](INFO): Protected caller Thread ID is 839 2025-07-03T01:46:13.717Z,1751507173.717 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-07-03T01:46:13.718Z,1751507173.718 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-07-03T01:46:13.718Z,1751507173.718 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 840 2025-07-03T01:46:13.722Z,1751507173.722 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-07-03T01:46:13.743Z,1751507173.743 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-07-03T01:46:13.744Z,1751507173.744 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-07-03T01:46:13.744Z,1751507173.744 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 841 2025-07-03T01:46:13.748Z,1751507173.748 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-07-03T01:46:13.749Z,1751507173.749 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-07-03T01:46:13.750Z,1751507173.750 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 842 2025-07-03T01:46:13.752Z,1751507173.752 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-07-03T01:46:13.753Z,1751507173.753 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-07-03T01:46:13.753Z,1751507173.753 [logger ThreadHandler](INFO): Protected caller Thread ID is 843 2025-07-03T01:46:13.757Z,1751507173.757 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-07-03T01:46:13.758Z,1751507173.758 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-07-03T01:46:13.762Z,1751507173.762 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-07-03T01:46:13.872Z,1751507173.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-07-03T01:46:13.873Z,1751507173.873 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-07-03T01:46:14.697Z,1751507174.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-07-03T01:46:14.699Z,1751507174.699 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-07-03T01:46:14.962Z,1751507174.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-07-03T01:46:14.964Z,1751507174.964 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-07-03T01:46:15.055Z,1751507175.055 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-07-03T01:46:15.204Z,1751507175.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-07-03T01:46:15.205Z,1751507175.205 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-07-03T01:46:15.310Z,1751507175.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-07-03T01:46:15.312Z,1751507175.312 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-07-03T01:46:15.437Z,1751507175.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-07-03T01:46:15.438Z,1751507175.438 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-07-03T01:46:15.888Z,1751507175.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-07-03T01:46:15.890Z,1751507175.890 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-07-03T01:46:16.498Z,1751507176.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-07-03T01:46:16.500Z,1751507176.500 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-07-03T01:46:16.743Z,1751507176.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-07-03T01:46:16.743Z,1751507176.743 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-07-03T01:46:16.960Z,1751507176.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-07-03T01:46:17.282Z,1751507177.282 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-07-03T01:46:17.935Z,1751507177.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-07-03T01:46:17.936Z,1751507177.936 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-07-03T01:46:18.256Z,1751507178.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-07-03T01:46:18.258Z,1751507178.258 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-07-03T01:46:18.522Z,1751507178.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-07-03T01:46:18.524Z,1751507178.524 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-07-03T01:46:18.943Z,1751507178.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-07-03T01:46:18.943Z,1751507178.943 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-07-03T01:46:19.331Z,1751507179.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-07-03T01:46:19.333Z,1751507179.333 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2025-07-03T01:46:19.335Z,1751507179.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2025-07-03T01:46:19.431Z,1751507179.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2025-07-03T01:46:19.619Z,1751507179.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg 2025-07-03T01:46:19.739Z,1751507179.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2025-07-03T01:46:19.847Z,1751507179.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2025-07-03T01:46:19.970Z,1751507179.970 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2025-07-03T01:46:20.070Z,1751507180.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2025-07-03T01:46:20.180Z,1751507180.180 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2025-07-03T01:46:20.309Z,1751507180.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2025-07-03T01:46:20.603Z,1751507180.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-07-03T01:46:20.604Z,1751507180.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2025-07-03T01:46:20.701Z,1751507180.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2025-07-03T01:46:20.808Z,1751507180.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2025-07-03T01:46:20.966Z,1751507180.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2025-07-03T01:46:21.076Z,1751507181.076 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-07-03T01:46:21.081Z,1751507181.081 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-07-03T01:46:21.277Z,1751507181.277 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-07-03T01:46:21.277Z,1751507181.277 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-07-03T01:46:21.358Z,1751507181.358 [VerticalControl](DEBUG): Construct VerticalControl. 2025-07-03T01:46:21.423Z,1751507181.423 [VerticalControl] Loaded 2025-07-03T01:46:21.424Z,1751507181.424 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-07-03T01:46:21.427Z,1751507181.427 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-07-03T01:46:21.477Z,1751507181.477 [HorizontalControl] Loaded 2025-07-03T01:46:21.477Z,1751507181.477 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-07-03T01:46:21.479Z,1751507181.479 [SpeedControl](DEBUG): Construct SpeedControl. 2025-07-03T01:46:21.483Z,1751507181.483 [SpeedControl] Loaded 2025-07-03T01:46:21.483Z,1751507181.483 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-07-03T01:46:21.486Z,1751507181.486 [LoopControl](DEBUG): Construct LoopControl. 2025-07-03T01:46:21.486Z,1751507181.486 [LoopControl] Loaded 2025-07-03T01:46:21.487Z,1751507181.487 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-07-03T01:46:21.487Z,1751507181.487 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-07-03T01:46:21.488Z,1751507181.488 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-07-03T01:46:21.565Z,1751507181.565 [DepthRateCalculator] Loaded 2025-07-03T01:46:21.566Z,1751507181.566 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-07-03T01:46:21.571Z,1751507181.571 [PitchRateCalculator] Loaded 2025-07-03T01:46:21.571Z,1751507181.571 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-07-03T01:46:21.582Z,1751507181.582 [SpeedCalculator] Loaded 2025-07-03T01:46:21.582Z,1751507181.582 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-07-03T01:46:21.587Z,1751507181.587 [YawRateCalculator] Loaded 2025-07-03T01:46:21.587Z,1751507181.587 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-07-03T01:46:21.606Z,1751507181.606 [ElevatorOffsetCalculator] Loaded 2025-07-03T01:46:21.607Z,1751507181.607 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-07-03T01:46:21.607Z,1751507181.607 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-07-03T01:46:21.609Z,1751507181.609 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-07-03T01:46:21.660Z,1751507181.660 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-07-03T01:46:21.661Z,1751507181.661 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-07-03T01:46:21.758Z,1751507181.758 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-07-03T01:46:21.759Z,1751507181.759 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-07-03T01:46:22.221Z,1751507182.221 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-07-03T01:46:22.221Z,1751507182.221 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-07-03T01:46:22.927Z,1751507182.927 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-07-03T01:46:22.928Z,1751507182.928 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-07-03T01:46:23.877Z,1751507183.877 [AHRS_M2] Loaded 2025-07-03T01:46:23.878Z,1751507183.878 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-07-03T01:46:24.133Z,1751507184.133 [BackseatComponent] Loaded 2025-07-03T01:46:24.133Z,1751507184.133 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-07-03T01:46:24.134Z,1751507184.134 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0 2025-07-03T01:46:24.135Z,1751507184.135 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 932 2025-07-03T01:46:24.138Z,1751507184.138 [LcmUniversalReporter] Loaded 2025-07-03T01:46:24.138Z,1751507184.138 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-07-03T01:46:25.018Z,1751507185.018 [BPC1] Loaded 2025-07-03T01:46:25.018Z,1751507185.018 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-07-03T01:46:25.122Z,1751507185.122 [DAT] Loaded 2025-07-03T01:46:25.122Z,1751507185.122 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-07-03T01:46:25.123Z,1751507185.123 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0 2025-07-03T01:46:25.124Z,1751507185.124 [DAT ThreadHandler](INFO): Protected caller Thread ID is 933 2025-07-03T01:46:25.272Z,1751507185.272 [DataOverHttps] Loaded 2025-07-03T01:46:25.272Z,1751507185.272 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-07-03T01:46:25.273Z,1751507185.273 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0 2025-07-03T01:46:25.274Z,1751507185.274 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 934 2025-07-03T01:46:25.298Z,1751507185.298 [Depth_Keller] Loaded 2025-07-03T01:46:25.299Z,1751507185.299 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-07-03T01:46:25.304Z,1751507185.304 [DropWeight] Loaded 2025-07-03T01:46:25.304Z,1751507185.304 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-07-03T01:46:25.374Z,1751507185.374 [NAL9602] Loaded 2025-07-03T01:46:25.374Z,1751507185.374 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-07-03T01:46:25.406Z,1751507185.406 [Onboard] Loaded 2025-07-03T01:46:25.407Z,1751507185.407 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-07-03T01:46:25.408Z,1751507185.408 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A794E0 2025-07-03T01:46:25.408Z,1751507185.408 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 935 2025-07-03T01:46:25.423Z,1751507185.423 [Power24vConverter] Loaded 2025-07-03T01:46:25.424Z,1751507185.424 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-07-03T01:46:25.441Z,1751507185.441 [Radio_Surface] Loaded 2025-07-03T01:46:25.441Z,1751507185.441 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-07-03T01:46:25.442Z,1751507185.442 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2025-07-03T01:46:25.442Z,1751507185.442 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 936 2025-07-03T01:46:25.493Z,1751507185.493 [RDI_Pathfinder] Loaded 2025-07-03T01:46:25.493Z,1751507185.493 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-07-03T01:46:25.510Z,1751507185.510 [Sonardyne_Nano] Loaded 2025-07-03T01:46:25.510Z,1751507185.510 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-07-03T01:46:25.510Z,1751507185.510 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-07-03T01:46:25.511Z,1751507185.511 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-07-03T01:46:25.662Z,1751507185.662 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-07-03T01:46:25.662Z,1751507185.662 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-07-03T01:46:25.677Z,1751507185.677 [NavChart] Loaded 2025-07-03T01:46:25.677Z,1751507185.677 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-07-03T01:46:25.683Z,1751507185.683 [UniversalFixResidualReporter] Loaded 2025-07-03T01:46:25.683Z,1751507185.683 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-07-03T01:46:25.684Z,1751507185.684 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-07-03T01:46:25.685Z,1751507185.685 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-07-03T01:46:25.910Z,1751507185.910 [SBIT](DEBUG): Construct Startup Built In Test. 2025-07-03T01:46:25.919Z,1751507185.919 [SBIT] Loaded 2025-07-03T01:46:25.919Z,1751507185.919 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-07-03T01:46:25.923Z,1751507185.923 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-07-03T01:46:25.936Z,1751507185.936 [IBIT] Loaded 2025-07-03T01:46:25.937Z,1751507185.937 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-07-03T01:46:25.942Z,1751507185.942 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-07-03T01:46:26.028Z,1751507186.028 [CBIT] Loaded 2025-07-03T01:46:26.029Z,1751507186.029 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-07-03T01:46:26.063Z,1751507186.063 [GFScanner] Loaded 2025-07-03T01:46:26.064Z,1751507186.064 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-07-03T01:46:26.064Z,1751507186.064 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-07-03T01:46:26.065Z,1751507186.065 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-07-03T01:46:26.249Z,1751507186.249 [BuoyancyServo] Loaded 2025-07-03T01:46:26.249Z,1751507186.249 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-07-03T01:46:26.274Z,1751507186.274 [ElevatorServo] Loaded 2025-07-03T01:46:26.275Z,1751507186.275 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-07-03T01:46:26.306Z,1751507186.306 [DockingStepper] Loaded 2025-07-03T01:46:26.306Z,1751507186.306 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-07-03T01:46:26.330Z,1751507186.330 [MassServo] Loaded 2025-07-03T01:46:26.330Z,1751507186.330 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-07-03T01:46:26.352Z,1751507186.352 [RudderServo] Loaded 2025-07-03T01:46:26.353Z,1751507186.353 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-07-03T01:46:26.373Z,1751507186.373 [ThrusterServo] Loaded 2025-07-03T01:46:26.374Z,1751507186.374 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2025-07-03T01:46:26.374Z,1751507186.374 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-07-03T01:46:26.375Z,1751507186.375 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-07-03T01:46:26.391Z,1751507186.391 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-07-03T01:46:26.392Z,1751507186.392 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-07-03T01:46:26.634Z,1751507186.634 [CTD_Seabird] Loaded 2025-07-03T01:46:26.634Z,1751507186.634 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-07-03T01:46:26.636Z,1751507186.636 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0C4E0 2025-07-03T01:46:26.636Z,1751507186.636 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 937 2025-07-03T01:46:26.660Z,1751507186.660 [PAR_Licor] Loaded 2025-07-03T01:46:26.660Z,1751507186.660 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-07-03T01:46:26.708Z,1751507186.708 [WetLabsBB2FL] Loaded 2025-07-03T01:46:26.709Z,1751507186.709 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-07-03T01:46:26.710Z,1751507186.710 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3C4E0 2025-07-03T01:46:26.710Z,1751507186.710 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 938 2025-07-03T01:46:26.733Z,1751507186.733 [WetLabsUBAT] Loaded 2025-07-03T01:46:26.733Z,1751507186.733 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2025-07-03T01:46:26.734Z,1751507186.734 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6C4E0 2025-07-03T01:46:26.735Z,1751507186.735 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 939 2025-07-03T01:46:26.735Z,1751507186.735 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-07-03T01:46:26.742Z,1751507186.742 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-07-03T01:46:26.745Z,1751507186.745 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-07-03T01:46:26.757Z,1751507186.757 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-07-03T01:46:26.758Z,1751507186.758 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9C4E0 2025-07-03T01:46:26.758Z,1751507186.758 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 940 2025-07-03T01:46:26.762Z,1751507186.762 [Supervisor](INFO): Main Thread ID is 830 2025-07-03T01:46:26.762Z,1751507186.762 [Supervisor](DEBUG): Running supervisor. 2025-07-03T01:46:26.763Z,1751507186.763 [CommandExec ThreadHandler](INFO): Handler Thread ID is 941 2025-07-03T01:46:26.763Z,1751507186.763 [CommandExec](INFO): Initializing the command executive. 2025-07-03T01:46:26.765Z,1751507186.765 [CommandLine ThreadHandler](INFO): Handler Thread ID is 942 2025-07-03T01:46:26.767Z,1751507186.767 [controlThread ThreadHandler](INFO): Handler Thread ID is 943 2025-07-03T01:46:26.768Z,1751507186.768 [controlThread](DEBUG): Initializing ControlThread 2025-07-03T01:46:26.769Z,1751507186.769 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-07-03T01:46:26.770Z,1751507186.770 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-07-03T01:46:26.771Z,1751507186.771 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-07-03T01:46:26.771Z,1751507186.771 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-07-03T01:46:26.772Z,1751507186.772 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-07-03T01:46:26.772Z,1751507186.772 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-07-03T01:46:26.773Z,1751507186.773 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-07-03T01:46:26.773Z,1751507186.773 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-07-03T01:46:26.773Z,1751507186.773 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-07-03T01:46:26.777Z,1751507186.777 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-07-03T01:46:26.778Z,1751507186.778 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-07-03T01:46:26.778Z,1751507186.778 [SBIT](INFO): Initialize SBIT Component. 2025-07-03T01:46:26.779Z,1751507186.779 [SBIT](IMPORTANT): git: 2025-06-05-2-g07de84f03 2025-07-03T01:46:26.779Z,1751507186.779 [SBIT](INFO): git hash: 07de84f036b9b391a47d8c2a08834ee5eaa918cc 2025-07-03T01:46:26.779Z,1751507186.779 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-07-03T01:46:26.781Z,1751507186.781 [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-07-03T01:46:26.782Z,1751507186.782 [SBIT](INFO): Beginning SBIT in 46.000000 seconds. 2025-07-03T01:46:26.782Z,1751507186.782 [IBIT](INFO): Initialize IBIT Component. 2025-07-03T01:46:26.783Z,1751507186.783 [CBIT](DEBUG): Initialize CBIT Component. 2025-07-03T01:46:26.784Z,1751507186.784 [logger ThreadHandler](INFO): Handler Thread ID is 944 2025-07-03T01:46:26.796Z,1751507186.796 [CBIT](DEBUG): Initialized mux pins. 2025-07-03T01:46:26.797Z,1751507186.797 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-07-03T01:46:26.797Z,1751507186.797 [CBIT](DEBUG): Initializing the watchdog timer. 2025-07-03T01:46:26.805Z,1751507186.805 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 945 2025-07-03T01:46:26.820Z,1751507186.820 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2025-07-03T01:46:26.821Z,1751507186.821 [CBIT](DEBUG): Initializing heartbeat. 2025-07-03T01:46:26.822Z,1751507186.822 [DAT ThreadHandler](INFO): Handler Thread ID is 946 2025-07-03T01:46:26.822Z,1751507186.822 [DAT](INFO): Powering up 2025-07-03T01:46:26.823Z,1751507186.823 [DAT](DEBUG): Initializing DAT. 2025-07-03T01:46:26.826Z,1751507186.826 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 948 2025-07-03T01:46:26.828Z,1751507186.828 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-07-03T01:46:26.849Z,1751507186.849 [Onboard ThreadHandler](INFO): Handler Thread ID is 949 2025-07-03T01:46:26.867Z,1751507186.867 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 950 2025-07-03T01:46:26.887Z,1751507186.887 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 951 2025-07-03T01:46:26.887Z,1751507186.887 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-07-03T01:46:26.893Z,1751507186.893 [CBIT](DEBUG): Deactivating emergency mode. 2025-07-03T01:46:26.901Z,1751507186.901 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 953 2025-07-03T01:46:26.905Z,1751507186.905 [WetLabsBB2FL](INFO): Powering up 2025-07-03T01:46:26.906Z,1751507186.906 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 955 2025-07-03T01:46:26.909Z,1751507186.909 [WetLabsUBAT](INFO): Powering up 2025-07-03T01:46:26.910Z,1751507186.910 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 957 2025-07-03T01:46:26.919Z,1751507186.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-07-03T01:46:26.919Z,1751507186.919 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-07-03T01:46:26.919Z,1751507186.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-07-03T01:46:26.919Z,1751507186.919 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-07-03T01:46:26.920Z,1751507186.920 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-07-03T01:46:26.920Z,1751507186.920 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-07-03T01:46:26.920Z,1751507186.920 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-07-03T01:46:26.920Z,1751507186.920 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-07-03T01:46:26.933Z,1751507186.933 [CBIT](DEBUG): Backplane powered. 2025-07-03T01:46:26.933Z,1751507186.933 [GFScanner](DEBUG): Initializing GFScanner 2025-07-03T01:46:26.933Z,1751507186.933 [GFScanner](DEBUG): Deactivating GF circuits. 2025-07-03T01:46:26.941Z,1751507186.941 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-07-03T01:46:26.942Z,1751507186.942 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2025-07-03T01:46:26.942Z,1751507186.942 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2025-07-03T01:46:26.962Z,1751507186.962 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2025-07-03T01:46:26.979Z,1751507186.979 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2025-07-03T01:46:26.980Z,1751507186.980 [MissionManager](ERROR): Top node has no child nodes: Mission 2025-07-03T01:46:26.980Z,1751507186.980 [MissionManager](ERROR): Could not load startup mission. 2025-07-03T01:46:26.981Z,1751507186.981 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2025-07-03T01:46:26.981Z,1751507186.981 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2025-07-03T01:46:26.985Z,1751507186.985 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2025-07-03T01:46:27.273Z,1751507187.273 [Radio_Surface](INFO): Powering up 2025-07-03T01:46:27.545Z,1751507187.545 [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-07-03T01:46:27.563Z,1751507187.563 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2025-07-03T01:46:27.576Z,1751507187.576 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2025-07-03T01:46:27.588Z,1751507187.588 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2025-07-03T01:46:27.592Z,1751507187.592 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2025-07-03T01:46:27.612Z,1751507187.612 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2025-07-03T01:46:27.616Z,1751507187.616 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2025-07-03T01:46:27.637Z,1751507187.637 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2025-07-03T01:46:27.792Z,1751507187.792 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2025-07-03T01:46:27.792Z,1751507187.792 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-07-03T01:46:27.855Z,1751507187.855 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-03T01:46:27.900Z,1751507187.900 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-07-03T01:46:27.903Z,1751507187.903 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-07-03T01:46:27.929Z,1751507187.929 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2025-07-03T01:46:27.935Z,1751507187.935 [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-07-03T01:46:27.953Z,1751507187.953 [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,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-07-03T01:46:27.969Z,1751507187.969 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-07-03T01:46:28.118Z,1751507188.118 [Depth_Keller](INFO): Initializing. 2025-07-03T01:46:28.119Z,1751507188.119 [Power24vConverter](INFO): Powering up. 2025-07-03T01:46:28.120Z,1751507188.120 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-07-03T01:46:28.125Z,1751507188.125 [Sonardyne_Nano](INFO): Initializing. 2025-07-03T01:46:28.169Z,1751507188.169 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-03T01:46:28.201Z,1751507188.201 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-07-03T01:46:28.209Z,1751507188.209 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-07-03T01:46:28.210Z,1751507188.210 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-03T01:46:28.218Z,1751507188.218 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-03T01:46:28.218Z,1751507188.218 [DockingStepper](INFO): Start 2025-07-03T01:46:28.219Z,1751507188.219 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-07-03T01:46:28.225Z,1751507188.225 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-07-03T01:46:28.226Z,1751507188.226 [MassServo](DEBUG): Initializing EZServoServo. 2025-07-03T01:46:28.233Z,1751507188.233 [MassServo](DEBUG): Initializing MassServo. 2025-07-03T01:46:28.234Z,1751507188.234 [RudderServo](DEBUG): Initializing EZServoServo. 2025-07-03T01:46:28.245Z,1751507188.245 [RudderServo](DEBUG): Initializing RudderServo. 2025-07-03T01:46:28.246Z,1751507188.246 [ThrusterServo](DEBUG): Initializing EZServoServo. 2025-07-03T01:46:28.253Z,1751507188.253 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2025-07-03T01:46:28.255Z,1751507188.255 [CommandExec](FAULT): Scheduling is paused 2025-07-03T01:46:28.255Z,1751507188.255 [CBIT](INFO): Critical error at 20250703T014626 2025-07-03T01:46:28.255Z,1751507188.255 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-07-03T01:46:28.421Z,1751507188.421 [DockingStepper](INFO): Starting 2025-07-03T01:46:28.788Z,1751507188.788 [DockingStepper](INFO): Starting 2025-07-03T01:46:29.313Z,1751507189.313 [DockingStepper](INFO): Starting 2025-07-03T01:46:29.362Z,1751507189.362 [DockingStepper](INFO): Init string: /1z16367aE13561N3m50 2025-07-03T01:46:29.525Z,1751507189.525 [WetLabsBB2FL](INFO): Powering down 2025-07-03T01:46:29.601Z,1751507189.601 [DockingStepper](INFO): Starting 2025-07-03T01:46:29.000Z,1751507190.000 [DockingStepper](INFO): Starting 2025-07-03T01:46:30.387Z,1751507190.387 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-07-03T01:46:30.432Z,1751507190.432 [DockingStepper](INFO): Starting 2025-07-03T01:46:30.857Z,1751507190.857 [DockingStepper](INFO): Starting 2025-07-03T01:46:30.881Z,1751507190.881 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-07-03T01:46:31.383Z,1751507191.383 [DockingStepper](INFO): Starting 2025-07-03T01:46:31.642Z,1751507191.642 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103 2025-07-03T01:46:32.186Z,1751507192.186 [DockingStepper](INFO): Pause 2025-07-03T01:46:32.525Z,1751507192.525 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2025-07-03T01:46:32.526Z,1751507192.526 [Radio_Surface](INFO): No local IP found 2025-07-03T01:46:33.333Z,1751507193.333 [Radio_Surface](INFO): Powering down 2025-07-03T01:46:36.229Z,1751507196.229 [WetLabsUBAT](INFO): Powering down 2025-07-03T01:46:40.189Z,1751507200.189 [DAT](INFO): DAT read: 2025-07-03T01:46:40.191Z,1751507200.191 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-07-03T01:46:45.734Z,1751507205.734 [DAT](INFO): DAT read: MF Frequency Band 2025-07-03T01:46:45.735Z,1751507205.735 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2025-07-03T01:46:45.736Z,1751507205.736 [DAT](INFO): DAT read: Jul 3 2025 01:45:46 2025-07-03T01:46:46.994Z,1751507206.994 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-07-03T01:46:46.995Z,1751507206.995 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-07-03T01:46:46.995Z,1751507206.995 [DAT](INFO): commRate: 600 2025-07-03T01:46:49.061Z,1751507209.061 [DAT](INFO): entering command mode 2025-07-03T01:46:49.261Z,1751507209.261 [DAT](INFO): DAT read: 2025-07-03T01:46:49.262Z,1751507209.262 [DAT](INFO): DAT read: user:1> 2025-07-03T01:46:49.262Z,1751507209.262 [DAT](INFO): setting verbose to 3 2025-07-03T01:46:49.516Z,1751507209.516 [DAT](INFO): DAT read: user:1> 2025-07-03T01:46:49.517Z,1751507209.517 [DAT](INFO): DAT read: Verbose | 3 2025-07-03T01:46:49.517Z,1751507209.517 [DAT](INFO): set verbose to 3 2025-07-03T01:46:49.518Z,1751507209.518 [DAT](INFO): setting DatVerbose to 27440 2025-07-03T01:46:49.767Z,1751507209.767 [DAT](INFO): DAT read: user:2> 2025-07-03T01:46:49.771Z,1751507209.771 [DAT](INFO): DAT read: DatVerbose | 27440 2025-07-03T01:46:49.772Z,1751507209.772 [DAT](INFO): set DatVerbose to 27440 2025-07-03T01:46:49.776Z,1751507209.776 [DAT](INFO): setting transmit power to 8 2025-07-03T01:46:50.017Z,1751507210.017 [DAT](INFO): DAT read: user:3> 2025-07-03T01:46:50.018Z,1751507210.018 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-07-03T01:46:50.019Z,1751507210.019 [DAT](INFO): set transmit power to 8 2025-07-03T01:46:50.019Z,1751507210.019 [DAT](INFO): setting local address to 10 2025-07-03T01:46:50.269Z,1751507210.269 [DAT](INFO): DAT read: user:4> 2025-07-03T01:46:50.270Z,1751507210.270 [DAT](INFO): DAT read: LocalAddr | 10 2025-07-03T01:46:50.270Z,1751507210.270 [DAT](INFO): set local address to 10 2025-07-03T01:46:50.272Z,1751507210.272 [DAT](INFO): Setting time to: 1:46:50 And date to:7/3/2025 2025-07-03T01:46:50.521Z,1751507210.521 [DAT](INFO): DAT read: user:5> 2025-07-03T01:46:50.522Z,1751507210.522 [DAT](INFO): DAT read: Thu Jul 3, 2025 01:46:50 2025-07-03T01:46:50.523Z,1751507210.523 [DAT](INFO): Local DAT time set to Thu Jul 3, 2025 01:46:50 2025-07-03T01:46:55.518Z,1751507215.518 [NAL9602](INFO): Powering up NAL9602 2025-07-03T01:46:57.167Z,1751507217.167 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2025-07-03T01:46:57.168Z,1751507217.168 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-07-03T01:46:57.168Z,1751507217.168 [RDI_Pathfinder](ERROR): Communications Fault 2025-07-03T01:46:57.269Z,1751507217.269 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-07-03T01:46:57.617Z,1751507217.617 [RDI_Pathfinder](INFO): Powering down 2025-07-03T01:46:58.449Z,1751507218.449 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-07-03T01:46:58.449Z,1751507218.449 [RDI_Pathfinder] No Fault, FailCount= 1 2025-07-03T01:46:58.746Z,1751507218.746 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-07-03T01:47:06.422Z,1751507226.422 [NAL9602](INFO): NAL9602 initialized 2025-07-03T01:47:13.383Z,1751507233.383 [SBIT](IMPORTANT): Beginning Startup BIT 2025-07-03T01:47:13.392Z,1751507233.392 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-03T01:47:17.862Z,1751507237.862 [GFScanner](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 1.000336 CHAN A1 (24V): 0.904922 CHAN A2 (12V): 1.010755 CHAN A3 (5V): 1.064203 CHAN B0 (3.3V): 1.068631 CHAN B1 (3.15aV): 1.081661 CHAN B2 (3.15bV): 1.081452 CHAN B3 (GND): 1.009851 OPEN: 0.049271 Full Scale: +/- 1 mA 2025-07-03T01:47:35.136Z,1751507255.136 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-03T01:48:01.870Z,1751507281.870 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-03T01:48:07.123Z,1751507287.123 [SBIT](IMPORTANT): SBIT PASSED 2025-07-03T01:48:07.123Z,1751507287.123 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-07-03T01:48:07.475Z,1751507287.475 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-07-03T01:48:07.475Z,1751507287.475 [DefaultWithUndock] Running Loop=1 2025-07-03T01:48:07.475Z,1751507287.475 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-07-03T01:48:07.476Z,1751507287.476 [DefaultWithUndock:A.Wait] Running Loop=1 2025-07-03T01:48:07.476Z,1751507287.476 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-07-03T01:48:37.779Z,1751507317.779 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-07-03T01:48:37.779Z,1751507317.779 [DefaultWithUndock:A.Wait] Stopped 2025-07-03T01:48:37.780Z,1751507317.780 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T01:48:37.780Z,1751507317.780 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-07-03T01:48:37.780Z,1751507317.780 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-07-03T01:48:37.780Z,1751507317.780 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-07-03T01:48:37.780Z,1751507317.780 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-07-03T01:48:37.780Z,1751507317.780 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-07-03T01:48:37.781Z,1751507317.781 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-07-03T01:48:37.781Z,1751507317.781 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-07-03T01:48:38.179Z,1751507318.179 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-07-03T01:48:38.179Z,1751507318.179 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-07-03T01:48:38.181Z,1751507318.181 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock at depth 63.873379 m. 2025-07-03T01:48:38.182Z,1751507318.182 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-07-03T01:48:38.328Z,1751507318.328 [DockingStepper](INFO): Resume 2025-07-03T01:48:38.638Z,1751507318.638 [DockingStepper](INFO): Resuming 2025-07-03T01:48:39.005Z,1751507319.005 [DockingStepper](INFO): Start 2025-07-03T01:48:39.005Z,1751507319.005 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-07-03T01:48:39.122Z,1751507319.122 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-07-03T01:48:39.400Z,1751507319.400 [DockingStepper](INFO): Starting 2025-07-03T01:48:39.861Z,1751507319.861 [DockingStepper](INFO): Starting 2025-07-03T01:48:40.201Z,1751507320.201 [DockingStepper](INFO): Starting 2025-07-03T01:48:40.249Z,1751507320.249 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50 2025-07-03T01:48:40.627Z,1751507320.627 [DockingStepper](INFO): Starting 2025-07-03T01:48:40.990Z,1751507320.990 [DockingStepper](INFO): Starting 2025-07-03T01:48:41.450Z,1751507321.450 [CTD_Seabird](ERROR): Failed to parse device response: 2025-07-03T01:48:41.451Z,1751507321.451 [DockingStepper](INFO): Starting 2025-07-03T01:48:41.817Z,1751507321.817 [DockingStepper](INFO): Starting 2025-07-03T01:48:41.845Z,1751507321.845 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-07-03T01:48:42.349Z,1751507322.349 [DockingStepper](INFO): Starting 2025-07-03T01:48:42.662Z,1751507322.662 [DockingStepper](INFO): Changing to mode: 3 2025-07-03T01:48:42.662Z,1751507322.662 [DockingStepper](INFO): Detach mode. 2025-07-03T01:48:42.725Z,1751507322.725 [DockingStepper](INFO): Commanding negative:54500 2025-07-03T01:48:47.853Z,1751507327.853 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:48:48.049Z,1751507328.049 [DAT](DEBUG): Acoustic response timeout 2025-07-03T01:48:48.050Z,1751507328.050 [DAT](INFO): setting remote address to 0 2025-07-03T01:48:48.302Z,1751507328.302 [DAT](INFO): DAT read: user:6> 2025-07-03T01:48:48.302Z,1751507328.302 [DAT](INFO): DAT read: RemoteAddr | 0 2025-07-03T01:48:48.303Z,1751507328.303 [DAT](INFO): set remote address to 0 2025-07-03T01:48:48.303Z,1751507328.303 [DAT](DEBUG): Acoustic response timeout 2025-07-03T01:48:48.304Z,1751507328.304 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:48:48.553Z,1751507328.553 [DAT](INFO): DAT read: user:7> 2025-07-03T01:48:48.554Z,1751507328.554 [DAT](INFO): DAT read: Tx time:01:48:48.1925 2025-07-03T01:48:48.554Z,1751507328.554 [DAT](INFO): Ping request sent. 2025-07-03T01:48:48.555Z,1751507328.555 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:48:48.555Z,1751507328.555 [DAT](INFO): publishing transmit ping time 2025-07-03T01:48:48.556Z,1751507328.556 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000959 2025-07-03T01:48:48.696Z,1751507328.696 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:48.697Z,1751507328.697 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-07-03T01:48:48.806Z,1751507328.806 [DAT](INFO): DAT read: TxSync time:01:48:48.1917 2025-07-03T01:48:48.807Z,1751507328.807 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252118 2025-07-03T01:48:49.057Z,1751507329.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502832 2025-07-03T01:48:49.104Z,1751507329.104 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:49.310Z,1751507329.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754969 2025-07-03T01:48:49.556Z,1751507329.556 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:49.562Z,1751507329.562 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007027 2025-07-03T01:48:49.814Z,1751507329.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258977 2025-07-03T01:48:49.880Z,1751507329.880 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:50.066Z,1751507330.066 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510927 2025-07-03T01:48:50.281Z,1751507330.281 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:50.326Z,1751507330.326 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.771299 2025-07-03T01:48:50.578Z,1751507330.578 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.022950 2025-07-03T01:48:50.708Z,1751507330.708 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:50.830Z,1751507330.830 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274968 2025-07-03T01:48:51.082Z,1751507331.082 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.527730 2025-07-03T01:48:51.094Z,1751507331.094 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:51.334Z,1751507331.334 [DAT](INFO): DAT read: Rx Time:01:48:50.5980 2025-07-03T01:48:51.334Z,1751507331.334 [DAT](INFO): Rx dataTimestamp_ set to:1751507331.334051 2025-07-03T01:48:51.335Z,1751507331.335 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.780259 2025-07-03T01:48:51.578Z,1751507331.578 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:51.587Z,1751507331.587 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.032493 2025-07-03T01:48:51.840Z,1751507331.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.284917 2025-07-03T01:48:51.903Z,1751507331.903 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-07-03T01:48:52.097Z,1751507332.097 [DAT](INFO): DAT read: 01:48:50.5981 LVL= 32752, 20673, 32754, 32755, AGC= 39, IDX= 440, 0.32, 2.458, 2.421, 2.195,-2.348, PHS=-1.389,-1.467,-1.743, RAW= 42.1, 78.8, CAL= 41.3, 80.1, ROT= 108.7, -80.1 2025-07-03T01:48:52.098Z,1751507332.098 [DAT](INFO): got valid direction response: 01:48:50.5981 LVL= 32752, 20673, 32754, 32755, AGC= 39, IDX= 440, 0.32, 2.458, 2.421, 2.195,-2.348, PHS=-1.389,-1.467,-1.743, RAW= 42.1, 78.8, CAL= 41.3, 80.1, ROT= 108.7, -80.1 2025-07-03T01:48:52.100Z,1751507332.100 [DAT](INFO): DAT read: Bearing 108.7, -80.1 (Local) 2025-07-03T01:48:52.100Z,1751507332.100 [DAT](INFO): Local bearing/azimuth received: Bearing 108.7, -80.1 (Local) 2025-07-03T01:48:52.102Z,1751507332.102 [DAT](INFO): DAT read: Range 10 to 50 : 0.9 m (Round-trip 1.3 ms) speed 0.0 m/s 2025-07-03T01:48:52.103Z,1751507332.103 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-07-03T01:48:52.105Z,1751507332.105 [DAT](INFO): direction in FSK: [-0.055123,0.162853,0.985109] 2025-07-03T01:48:52.106Z,1751507332.106 [DAT](INFO): publishing direction and range info 2025-07-03T01:48:57.974Z,1751507337.974 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:48:58.141Z,1751507338.141 [DAT](INFO): ****** received valid address query ****** 2025-07-03T01:48:58.141Z,1751507338.141 [DAT](INFO): ****** received valid ping request ****** 2025-07-03T01:48:58.142Z,1751507338.142 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:48:58.393Z,1751507338.393 [DAT](INFO): DAT read: user:8> 2025-07-03T01:48:58.394Z,1751507338.394 [DAT](INFO): DAT read: Tx time:01:48:58.0426 2025-07-03T01:48:58.395Z,1751507338.395 [DAT](INFO): Ping request sent. 2025-07-03T01:48:58.395Z,1751507338.395 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:48:58.395Z,1751507338.395 [DAT](INFO): publishing transmit ping time 2025-07-03T01:48:58.395Z,1751507338.395 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000751 2025-07-03T01:48:58.646Z,1751507338.646 [DAT](INFO): DAT read: TxSync time:01:48:58.0418 2025-07-03T01:48:58.647Z,1751507338.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252119 2025-07-03T01:48:58.812Z,1751507338.812 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off. 2025-07-03T01:48:58.899Z,1751507338.899 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504066 2025-07-03T01:48:59.150Z,1751507339.150 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754848 2025-07-03T01:48:59.402Z,1751507339.402 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006893 2025-07-03T01:48:59.654Z,1751507339.654 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259075 2025-07-03T01:48:59.906Z,1751507339.906 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510880 2025-07-03T01:49:00.158Z,1751507340.158 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762887 2025-07-03T01:49:00.411Z,1751507340.411 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016354 2025-07-03T01:49:00.664Z,1751507340.664 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.269153 2025-07-03T01:49:00.914Z,1751507340.914 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518911 2025-07-03T01:49:01.166Z,1751507341.166 [DAT](INFO): DAT read: Rx Time:01:49:00.4489 2025-07-03T01:49:01.166Z,1751507341.166 [DAT](INFO): Rx dataTimestamp_ set to:1751507341.165912 2025-07-03T01:49:01.167Z,1751507341.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772081 2025-07-03T01:49:01.418Z,1751507341.418 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022895 2025-07-03T01:49:01.676Z,1751507341.676 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.280943 2025-07-03T01:49:01.927Z,1751507341.927 [DAT](INFO): DAT read: 01:49:00.4489 LVL= 15024, 27329, 16866, 22723, AGC= 33, IDX= 450, 0.37,-0.361,-2.284,-2.106,-0.846, PHS= 0.573,-1.391,-1.263, RAW= 93.3, 21.1, CAL= 98.9, 27.4, ROT= 51.1, -27.4 2025-07-03T01:49:01.928Z,1751507341.928 [DAT](INFO): got valid direction response: 01:49:00.4489 LVL= 15024, 27329, 16866, 22723, AGC= 33, IDX= 450, 0.37,-0.361,-2.284,-2.106,-0.846, PHS= 0.573,-1.391,-1.263, RAW= 93.3, 21.1, CAL= 98.9, 27.4, ROT= 51.1, -27.4 2025-07-03T01:49:01.929Z,1751507341.929 [DAT](INFO): DAT read: Bearing 51.1, -27.4 (Local) 2025-07-03T01:49:01.929Z,1751507341.929 [DAT](INFO): Local bearing/azimuth received: Bearing 51.1, -27.4 (Local) 2025-07-03T01:49:01.930Z,1751507341.930 [DAT](INFO): DAT read: Range 10 to 50 : 1.5 m (Round-trip 2.1 ms) speed -0.3 m/s 2025-07-03T01:49:01.931Z,1751507341.931 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-07-03T01:49:01.932Z,1751507341.932 [DAT](INFO): direction in FSK: [0.557515,0.690936,0.460200] 2025-07-03T01:49:01.933Z,1751507341.933 [DAT](INFO): publishing direction and range info 2025-07-03T01:49:08.112Z,1751507348.112 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:49:08.225Z,1751507348.225 [DAT](INFO): ****** received valid address query ****** 2025-07-03T01:49:08.225Z,1751507348.225 [DAT](INFO): ****** received valid ping request ****** 2025-07-03T01:49:08.226Z,1751507348.226 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:49:08.478Z,1751507348.478 [DAT](INFO): DAT read: user:9> 2025-07-03T01:49:08.479Z,1751507348.479 [DAT](INFO): DAT read: Tx time:01:49:08.1427 2025-07-03T01:49:08.479Z,1751507348.479 [DAT](INFO): Ping request sent. 2025-07-03T01:49:08.479Z,1751507348.479 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:49:08.479Z,1751507348.479 [DAT](INFO): publishing transmit ping time 2025-07-03T01:49:08.480Z,1751507348.480 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000739 2025-07-03T01:49:08.733Z,1751507348.733 [DAT](INFO): DAT read: TxSync time:01:49:08.1419 2025-07-03T01:49:08.735Z,1751507348.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255893 2025-07-03T01:49:08.982Z,1751507348.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502532 2025-07-03T01:49:09.234Z,1751507349.234 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754591 2025-07-03T01:49:09.486Z,1751507349.486 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006640 2025-07-03T01:49:09.740Z,1751507349.740 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260308 2025-07-03T01:49:09.990Z,1751507349.990 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510599 2025-07-03T01:49:10.242Z,1751507350.242 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762635 2025-07-03T01:49:10.493Z,1751507350.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014516 2025-07-03T01:49:10.746Z,1751507350.746 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266869 2025-07-03T01:49:10.999Z,1751507350.999 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519834 2025-07-03T01:49:11.253Z,1751507351.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772785 2025-07-03T01:49:11.502Z,1751507351.502 [DAT](INFO): DAT read: Rx Time:01:49:10.5520 2025-07-03T01:49:11.502Z,1751507351.502 [DAT](INFO): Rx dataTimestamp_ set to:1751507351.501737 2025-07-03T01:49:11.503Z,1751507351.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023597 2025-07-03T01:49:11.758Z,1751507351.758 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277900 2025-07-03T01:49:12.012Z,1751507352.012 [DAT](INFO): DAT read: 01:49:10.5520 LVL= 17312, 22449, 18962, 30067, AGC= 35, IDX= 449,-0.11, 2.044, 0.118,-0.023, 1.132, PHS= 1.000,-0.967,-1.158, RAW= 85.4, 10.9, CAL= 92.1, 14.4, ROT= 57.9, -14.4 2025-07-03T01:49:12.013Z,1751507352.013 [DAT](INFO): got valid direction response: 01:49:10.5520 LVL= 17312, 22449, 18962, 30067, AGC= 35, IDX= 449,-0.11, 2.044, 0.118,-0.023, 1.132, PHS= 1.000,-0.967,-1.158, RAW= 85.4, 10.9, CAL= 92.1, 14.4, ROT= 57.9, -14.4 2025-07-03T01:49:12.014Z,1751507352.014 [DAT](INFO): DAT read: Bearing 57.9, -14.4 (Local) 2025-07-03T01:49:12.014Z,1751507352.014 [DAT](INFO): Local bearing/azimuth received: Bearing 57.9, -14.4 (Local) 2025-07-03T01:49:12.015Z,1751507352.015 [DAT](INFO): DAT read: Range 10 to 50 : 3.8 m (Round-trip 5.1 ms) speed -0.2 m/s 2025-07-03T01:49:12.016Z,1751507352.016 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-07-03T01:49:12.017Z,1751507352.017 [DAT](INFO): direction in FSK: [0.514704,0.820508,0.248690] 2025-07-03T01:49:12.017Z,1751507352.017 [DAT](INFO): publishing direction and range info 2025-07-03T01:49:15.565Z,1751507355.565 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-07-03T01:49:15.565Z,1751507355.565 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:49:18.183Z,1751507358.183 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:49:18.305Z,1751507358.305 [DAT](INFO): ****** received valid address query ****** 2025-07-03T01:49:18.305Z,1751507358.305 [DAT](INFO): ****** received valid ping request ****** 2025-07-03T01:49:18.306Z,1751507358.306 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:49:18.558Z,1751507358.558 [DAT](INFO): DAT read: user:10> 2025-07-03T01:49:18.561Z,1751507358.561 [DAT](INFO): DAT read: Tx time:01:49:18.1927 2025-07-03T01:49:18.561Z,1751507358.561 [DAT](INFO): Ping request sent. 2025-07-03T01:49:18.562Z,1751507358.562 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:49:18.562Z,1751507358.562 [DAT](INFO): publishing transmit ping time 2025-07-03T01:49:18.563Z,1751507358.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001796 2025-07-03T01:49:18.810Z,1751507358.810 [DAT](INFO): DAT read: TxSync time:01:49:18.1919 2025-07-03T01:49:18.810Z,1751507358.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249059 2025-07-03T01:49:19.062Z,1751507359.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501083 2025-07-03T01:49:19.314Z,1751507359.314 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752232 2025-07-03T01:49:19.566Z,1751507359.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004169 2025-07-03T01:49:19.818Z,1751507359.818 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256129 2025-07-03T01:49:20.071Z,1751507360.071 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509234 2025-07-03T01:49:20.322Z,1751507360.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.760198 2025-07-03T01:49:20.574Z,1751507360.574 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012457 2025-07-03T01:49:20.826Z,1751507360.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.264208 2025-07-03T01:49:21.078Z,1751507361.078 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516693 2025-07-03T01:49:21.330Z,1751507361.330 [DAT](INFO): DAT read: Rx Time:01:49:20.6043 2025-07-03T01:49:21.330Z,1751507361.330 [DAT](INFO): Rx dataTimestamp_ set to:1751507361.329778 2025-07-03T01:49:21.331Z,1751507361.331 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769209 2025-07-03T01:49:21.582Z,1751507361.582 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020579 2025-07-03T01:49:21.836Z,1751507361.836 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274001 2025-07-03T01:49:22.093Z,1751507362.093 [DAT](INFO): DAT read: 01:49:20.6043 LVL= 12176, 24689, 20338, 28099, AGC= 38, IDX= 456, 0.31,-2.451, 2.074, 1.536, 2.827, PHS= 1.093,-0.707,-1.295, RAW= 76.3, 8.5, CAL= 79.9, 11.9, ROT= 70.1, -11.9 2025-07-03T01:49:22.094Z,1751507362.094 [DAT](INFO): got valid direction response: 01:49:20.6043 LVL= 12176, 24689, 20338, 28099, AGC= 38, IDX= 456, 0.31,-2.451, 2.074, 1.536, 2.827, PHS= 1.093,-0.707,-1.295, RAW= 76.3, 8.5, CAL= 79.9, 11.9, ROT= 70.1, -11.9 2025-07-03T01:49:22.095Z,1751507362.095 [DAT](INFO): DAT read: Bearing 70.1, -11.9 (Local) 2025-07-03T01:49:22.096Z,1751507362.096 [DAT](INFO): Local bearing/azimuth received: Bearing 70.1, -11.9 (Local) 2025-07-03T01:49:22.098Z,1751507362.098 [DAT](INFO): DAT read: Range 10 to 50 : 5.5 m (Round-trip 7.4 ms) speed -0.1 m/s 2025-07-03T01:49:22.098Z,1751507362.098 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-07-03T01:49:22.100Z,1751507362.100 [DAT](INFO): direction in FSK: [0.333064,0.920080,0.206204] 2025-07-03T01:49:22.100Z,1751507362.100 [DAT](INFO): publishing direction and range info 2025-07-03T01:49:27.114Z,1751507367.114 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -153, -155, BE, -153, -155, +3,A 2025-07-03T01:49:27.121Z,1751507367.121 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-07-03T01:49:27.121Z,1751507367.121 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-03T01:49:27.206Z,1751507367.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-03T01:49:27.651Z,1751507367.651 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-03T01:49:27.651Z,1751507367.651 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-07-03T01:49:28.303Z,1751507368.303 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:49:28.389Z,1751507368.389 [DAT](INFO): ****** received valid address query ****** 2025-07-03T01:49:28.389Z,1751507368.389 [DAT](INFO): ****** received valid ping request ****** 2025-07-03T01:49:28.390Z,1751507368.390 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:49:28.641Z,1751507368.641 [DAT](INFO): DAT read: user:11> 2025-07-03T01:49:28.642Z,1751507368.642 [DAT](INFO): DAT read: Tx time:01:49:28.2928 2025-07-03T01:49:28.642Z,1751507368.642 [DAT](INFO): Ping request sent. 2025-07-03T01:49:28.643Z,1751507368.643 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:49:28.643Z,1751507368.643 [DAT](INFO): publishing transmit ping time 2025-07-03T01:49:28.643Z,1751507368.643 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000751 2025-07-03T01:49:28.894Z,1751507368.894 [DAT](INFO): DAT read: TxSync time:01:49:28.2920 2025-07-03T01:49:28.895Z,1751507368.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252119 2025-07-03T01:49:29.148Z,1751507369.148 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.505012 2025-07-03T01:49:29.398Z,1751507369.398 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754920 2025-07-03T01:49:29.650Z,1751507369.650 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007801 2025-07-03T01:49:29.902Z,1751507369.902 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259581 2025-07-03T01:49:30.154Z,1751507370.154 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510949 2025-07-03T01:49:30.406Z,1751507370.406 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763004 2025-07-03T01:49:30.658Z,1751507370.658 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014968 2025-07-03T01:49:30.910Z,1751507370.910 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266917 2025-07-03T01:49:31.162Z,1751507371.162 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518940 2025-07-03T01:49:31.414Z,1751507371.414 [DAT](INFO): DAT read: Rx Time:01:49:30.7066 2025-07-03T01:49:31.414Z,1751507371.414 [DAT](INFO): Rx dataTimestamp_ set to:1751507371.413939 2025-07-03T01:49:31.415Z,1751507371.415 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772147 2025-07-03T01:49:31.666Z,1751507371.666 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022999 2025-07-03T01:49:31.918Z,1751507371.918 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275018 2025-07-03T01:49:32.179Z,1751507372.179 [DAT](INFO): DAT read: 01:49:30.7066 LVL= 13200, 23937, 17218, 28051, AGC= 37, IDX= 447, 0.26,-1.936, 2.801, 2.202,-2.863, PHS= 1.015,-0.573,-1.221, RAW= 73.6, 7.9, CAL= 76.4, 10.8, ROT= 73.6, -10.8 2025-07-03T01:49:32.180Z,1751507372.180 [DAT](INFO): got valid direction response: 01:49:30.7066 LVL= 13200, 23937, 17218, 28051, AGC= 37, IDX= 447, 0.26,-1.936, 2.801, 2.202,-2.863, PHS= 1.015,-0.573,-1.221, RAW= 73.6, 7.9, CAL= 76.4, 10.8, ROT= 73.6, -10.8 2025-07-03T01:49:32.181Z,1751507372.181 [DAT](INFO): DAT read: Bearing 73.6, -10.8 (Local) 2025-07-03T01:49:32.181Z,1751507372.181 [DAT](INFO): Local bearing/azimuth received: Bearing 73.6, -10.8 (Local) 2025-07-03T01:49:32.182Z,1751507372.182 [DAT](INFO): DAT read: Range 10 to 50 : 7.2 m (Round-trip 9.6 ms) speed -0.1 m/s 2025-07-03T01:49:32.183Z,1751507372.183 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-07-03T01:49:32.184Z,1751507372.184 [DAT](INFO): direction in FSK: [0.277340,0.942322,0.187381] 2025-07-03T01:49:32.184Z,1751507372.184 [DAT](INFO): publishing direction and range info 2025-07-03T01:49:32.338Z,1751507372.338 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-07-03T01:49:38.389Z,1751507378.389 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:49:38.469Z,1751507378.469 [DAT](INFO): ****** received valid address query ****** 2025-07-03T01:49:38.469Z,1751507378.469 [DAT](INFO): ****** received valid ping request ****** 2025-07-03T01:49:38.470Z,1751507378.470 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:49:38.722Z,1751507378.722 [DAT](INFO): DAT read: user:12> 2025-07-03T01:49:38.722Z,1751507378.722 [DAT](INFO): DAT read: Tx time:01:49:38.3929 2025-07-03T01:49:38.723Z,1751507378.723 [DAT](INFO): Ping request sent. 2025-07-03T01:49:38.723Z,1751507378.723 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:49:38.723Z,1751507378.723 [DAT](INFO): publishing transmit ping time 2025-07-03T01:49:38.724Z,1751507378.724 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000744 2025-07-03T01:49:38.974Z,1751507378.974 [DAT](INFO): DAT read: TxSync time:01:49:38.3921 2025-07-03T01:49:38.975Z,1751507378.975 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251672 2025-07-03T01:49:39.228Z,1751507379.228 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504660 2025-07-03T01:49:39.478Z,1751507379.478 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754676 2025-07-03T01:49:39.730Z,1751507379.730 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006754 2025-07-03T01:49:39.982Z,1751507379.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258781 2025-07-03T01:49:40.237Z,1751507380.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513854 2025-07-03T01:49:40.486Z,1751507380.486 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762699 2025-07-03T01:49:40.738Z,1751507380.738 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014680 2025-07-03T01:49:40.990Z,1751507380.990 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266950 2025-07-03T01:49:41.244Z,1751507381.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520580 2025-07-03T01:49:41.494Z,1751507381.494 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770669 2025-07-03T01:49:41.746Z,1751507381.746 [DAT](INFO): DAT read: Rx Time:01:49:40.8085 2025-07-03T01:49:41.746Z,1751507381.746 [DAT](INFO): Rx dataTimestamp_ set to:1751507381.745817 2025-07-03T01:49:41.747Z,1751507381.747 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023776 2025-07-03T01:49:41.998Z,1751507381.998 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274725 2025-07-03T01:49:42.264Z,1751507382.264 [DAT](INFO): DAT read: 01:49:40.8085 LVL= 15488, 22737, 15730, 21939,, IDX= 450, 0.27,-1.572,-3.66,-2.607, PHS= 1.123,-0.388,-1.013, RAW= 73.5, 3.0, CAL= 76.8, 0.3, ROT= 73.2, -0.3 2025-07-03T01:49:42.268Z,1751507382.268 [DAT](INFO): unknown deviceResponse_: 01:49:40.8085 LVL= 15488, 22737, 15730, 21939,, IDX= 450, 0.27,-1.572,-3.66,-2.607, PHS= 1.123,-0.388,-1.013, RAW= 73.5, 3.0, CAL= 76.8, 0.3, ROT= 73.2, -0.3 2025-07-03T01:49:42.271Z,1751507382.271 [DAT](INFO): DAT read: Bearing 73.2, -0.3 (Local) 2025-07-03T01:49:42.271Z,1751507382.271 [DAT](INFO): Local bearing/azimuth received: Bearing 73.2, -0.3 (Local) 2025-07-03T01:49:42.272Z,1751507382.272 [DAT](INFO): DAT read: Range 10 to 50 : 8.6 m (Round-trip 11.5 ms) speed -0.1 m/s 2025-07-03T01:49:42.273Z,1751507382.273 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-07-03T01:49:46.689Z,1751507386.689 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:49:48.482Z,1751507388.482 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:49:48.557Z,1751507388.557 [DAT](INFO): ****** received valid address query ****** 2025-07-03T01:49:48.557Z,1751507388.557 [DAT](INFO): ****** received valid ping request ****** 2025-07-03T01:49:48.558Z,1751507388.558 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:49:48.810Z,1751507388.810 [DAT](INFO): DAT read: user:13> 2025-07-03T01:49:48.810Z,1751507388.810 [DAT](INFO): DAT read: Tx time:01:49:48.4430 2025-07-03T01:49:48.811Z,1751507388.811 [DAT](INFO): Ping request sent. 2025-07-03T01:49:48.811Z,1751507388.811 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:49:48.811Z,1751507388.811 [DAT](INFO): DAT read: TxSync time:01:49:48.4422 2025-07-03T01:49:48.812Z,1751507388.812 [DAT](INFO): publishing transmit ping time 2025-07-03T01:49:48.812Z,1751507388.812 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001547 2025-07-03T01:49:49.062Z,1751507389.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250696 2025-07-03T01:49:49.314Z,1751507389.314 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503218 2025-07-03T01:49:49.566Z,1751507389.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754782 2025-07-03T01:49:49.818Z,1751507389.818 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006708 2025-07-03T01:49:50.069Z,1751507390.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258599 2025-07-03T01:49:50.324Z,1751507390.324 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512444 2025-07-03T01:49:50.574Z,1751507390.574 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762848 2025-07-03T01:49:50.826Z,1751507390.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014719 2025-07-03T01:49:51.078Z,1751507391.078 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267018 2025-07-03T01:49:51.333Z,1751507391.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522165 2025-07-03T01:49:51.582Z,1751507391.582 [DAT](INFO): DAT read: Rx Time:01:49:50.8607 2025-07-03T01:49:51.582Z,1751507391.582 [DAT](INFO): Rx dataTimestamp_ set to:1751507391.581818 2025-07-03T01:49:51.583Z,1751507391.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771820 2025-07-03T01:49:51.834Z,1751507391.834 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022793 2025-07-03T01:49:52.091Z,1751507392.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.280094 2025-07-03T01:49:52.362Z,1751507392.362 [DAT](INFO): DAT read: 01:49:50.8607 LVL= 14384, 25841, 18866, 24787, AGC= 39, IDX= 439,-0.29,-1.058,-2.741,-2.961,-2.187, PHS= 1.218,-0.507,-0.776, RAW= 82.9, 0.7, CAL= 89.0, -3.3, ROT= 61.0, 3.3 2025-07-03T01:49:52.365Z,1751507392.365 [DAT](INFO): got valid direction response: 01:49:50.8607 LVL= 14384, 25841, 18866, 24787, AGC= 39, IDX= 439,-0.29,-1.058,-2.741,-2.961,-2.187, PHS= 1.218,-0.507,-0.776, RAW= 82.9, 0.7, CAL= 89.0, -3.3, ROT= 61.0, 3.3 2025-07-03T01:49:52.367Z,1751507392.367 [DAT](INFO): DAT read: Bearing 61.0, 3.3 (Local) 2025-07-03T01:49:52.368Z,1751507392.368 [DAT](INFO): Local bearing/azimuth received: Bearing 61.0, 3.3 (Local) 2025-07-03T01:49:52.372Z,1751507392.372 [DAT](INFO): DAT read: Range 10 to 50 : 10.1 m (Round-trip 13.5 ms) speed -0.1 m/s 2025-07-03T01:49:52.374Z,1751507392.374 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-07-03T01:49:52.377Z,1751507392.377 [DAT](INFO): direction in FSK: [0.484006,0.873169,-0.057564] 2025-07-03T01:49:52.378Z,1751507392.378 [DAT](INFO): publishing direction and range info 2025-07-03T01:49:52.540Z,1751507392.540 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 10.10 m. Transitioning docking module to standby. 2025-07-03T01:49:52.909Z,1751507392.909 [DockingStepper](INFO): Changing to mode: 1 2025-07-03T01:49:52.909Z,1751507392.909 [DockingStepper](INFO): Standby mode. 2025-07-03T01:49:52.965Z,1751507392.965 [DockingStepper](INFO): Commanding positive:54599 2025-07-03T01:49:58.586Z,1751507398.586 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-07-03T01:49:58.654Z,1751507398.654 [DAT](INFO): ****** received valid address query ****** 2025-07-03T01:49:58.654Z,1751507398.654 [DAT](INFO): ****** received valid ping request ****** 2025-07-03T01:49:58.654Z,1751507398.654 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-07-03T01:49:58.906Z,1751507398.906 [DAT](INFO): DAT read: user:14> 2025-07-03T01:49:58.907Z,1751507398.907 [DAT](INFO): DAT read: Tx time:01:49:58.5430 2025-07-03T01:49:58.907Z,1751507398.907 [DAT](INFO): Ping request sent. 2025-07-03T01:49:58.907Z,1751507398.907 [DAT](INFO): transmitted an acoustic signal 2025-07-03T01:49:58.908Z,1751507398.908 [DAT](INFO): DAT read: TxSync time:01:49:58.5422 2025-07-03T01:49:58.908Z,1751507398.908 [DAT](INFO): publishing transmit ping time 2025-07-03T01:49:58.909Z,1751507398.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001695 2025-07-03T01:49:58.967Z,1751507398.967 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-07-03T01:49:59.158Z,1751507399.158 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250412 2025-07-03T01:49:59.371Z,1751507399.371 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-07-03T01:49:59.371Z,1751507399.371 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-07-03T01:49:59.371Z,1751507399.371 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-07-03T01:49:59.371Z,1751507399.371 [DefaultWithUndock:LeaveDock] Stopped 2025-07-03T01:49:59.371Z,1751507399.371 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-07-03T01:49:59.371Z,1751507399.371 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-07-03T01:49:59.371Z,1751507399.371 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-07-03T01:49:59.372Z,1751507399.372 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-07-03T01:49:59.372Z,1751507399.372 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-07-03T01:49:59.372Z,1751507399.372 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-07-03T01:49:59.372Z,1751507399.372 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-03T01:49:59.372Z,1751507399.372 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-03T01:49:59.373Z,1751507399.373 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-03T01:49:59.373Z,1751507399.373 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-03T01:49:59.374Z,1751507399.374 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-03T01:49:59.374Z,1751507399.374 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-03T01:49:59.374Z,1751507399.374 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-03T01:49:59.410Z,1751507399.410 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502386 2025-07-03T01:49:59.662Z,1751507399.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754144 2025-07-03T01:49:59.834Z,1751507399.834 [DockingStepper](INFO): Pause 2025-07-03T01:49:59.914Z,1751507399.914 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006341 2025-07-03T01:50:00.166Z,1751507400.166 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258278 2025-07-03T01:50:00.182Z,1751507400.182 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (1 active estimators). 2025-07-03T01:50:00.293Z,1751507400.293 [Radio_Surface](INFO): Powering up 2025-07-03T01:50:00.418Z,1751507400.418 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510187 2025-07-03T01:50:00.670Z,1751507400.670 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762170 2025-07-03T01:50:00.922Z,1751507400.922 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014214 2025-07-03T01:50:01.174Z,1751507401.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266474 2025-07-03T01:50:01.426Z,1751507401.426 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518483 2025-07-03T01:50:01.678Z,1751507401.678 [DAT](INFO): DAT read: Rx Time:01:50:00.9627 2025-07-03T01:50:01.679Z,1751507401.679 [DAT](INFO): Rx dataTimestamp_ set to:1751507401.678655 2025-07-03T01:50:01.680Z,1751507401.680 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772190 2025-07-03T01:50:01.930Z,1751507401.930 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022155 2025-07-03T01:50:02.185Z,1751507402.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276663 2025-07-03T01:50:02.439Z,1751507402.439 [DAT](INFO): DAT read: 01:50:00.9627 LVL= 15344, 32753, 23442, 32755, AGC= 40, IDX= 443, 0.43, 1.222,-0.459,-0.315, 0.254, PHS= 1.056,-0.666,-0.572, RAW= 92.8, 2.2, CAL= 100.9, -0.3, ROT= 49.1, 0.3 2025-07-03T01:50:02.440Z,1751507402.440 [DAT](INFO): got valid direction response: 01:50:00.9627 LVL= 15344, 32753, 23442, 32755, AGC= 40, IDX= 443, 0.43, 1.222,-0.459,-0.315, 0.254, PHS= 1.056,-0.666,-0.572, RAW= 92.8, 2.2, CAL= 100.9, -0.3, ROT= 49.1, 0.3 2025-07-03T01:50:02.441Z,1751507402.441 [DAT](INFO): DAT read: Bearing 49.1, 0.3 (Local) 2025-07-03T01:50:02.441Z,1751507402.441 [DAT](INFO): Local bearing/azimuth received: Bearing 49.1, 0.3 (Local) 2025-07-03T01:50:02.442Z,1751507402.442 [DAT](INFO): DAT read: Range 10 to 50 : 11.6 m (Round-trip 15.5 ms) speed -0.1 m/s 2025-07-03T01:50:02.443Z,1751507402.443 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-07-03T01:50:02.444Z,1751507402.444 [DAT](INFO): direction in FSK: [0.654732,0.755843,-0.005236] 2025-07-03T01:50:02.444Z,1751507402.444 [DAT](INFO): publishing direction and range info 2025-07-03T01:50:05.549Z,1751507405.549 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2025-07-03T01:50:05.550Z,1751507405.550 [Radio_Surface](INFO): Found local IP address 134.89.93.61 for eth0 2025-07-03T01:50:05.550Z,1751507405.550 [Radio_Surface](IMPORTANT): Local IP not on subnet 10.89.10, restarting DHCP 2025-07-03T01:50:05.643Z,1751507405.643 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2025-07-03T01:50:06.104Z,1751507406.104 [CommandExec](IMPORTANT): /mnt/mmc/started/2025-07-03UTC015006_sh 2025-07-03T01:50:17.813Z,1751507417.813 [DataOverHttps](INFO): Radio surface powered ON. 2025-07-03T01:50:17.813Z,1751507417.813 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:52:09.162Z,1751507529.162 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-03T01:52:20.500Z,1751507540.500 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-07-03T01:52:20.500Z,1751507540.500 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T01:52:20.500Z,1751507540.500 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-07-03T01:52:20.913Z,1751507540.913 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-07-03T01:52:28.150Z,1751507548.150 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-07-03T01:52:28.150Z,1751507548.150 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-03T01:52:28.229Z,1751507548.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-03T01:52:28.663Z,1751507548.663 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-03T01:52:28.664Z,1751507548.664 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-07-03T01:52:42.753Z,1751507562.753 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, .00 2025-07-03T01:53:03.505Z,1751507583.505 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:53:34.629Z,1751507614.629 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:54:02.455Z,1751507642.455 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004457 2025-07-03T01:54:03.453Z,1751507643.453 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:54:20.876Z,1751507660.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015421.00,A,3654.34588,N,12206.90059,W,0.583,0.00,030725,,,A*76 2025-07-03T01:54:20.879Z,1751507660.879 [NAL9602](INFO): GPS fix at 20250703T015421: (36.905765, -122.115010) 2025-07-03T01:54:20.959Z,1751507660.959 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-07-03T01:54:20.959Z,1751507660.959 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-07-03T01:54:21.379Z,1751507661.379 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-03T01:54:33.108Z,1751507673.108 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250703T011033/Courier0015.lzma 2025-07-03T01:54:44.167Z,1751507684.167 [DataOverHttps](INFO): Moved sent file to Logs/20250703T011033/Courier0015.lzma.bak 2025-07-03T01:54:44.167Z,1751507684.167 [DataOverHttps](INFO): SBD MOMSN=25309014 2025-07-03T01:54:53.223Z,1751507693.223 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-03T01:55:15.837Z,1751507715.837 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20250703T014613/Courier0000.lzma 2025-07-03T01:55:24.878Z,1751507724.878 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Courier0000.lzma.bak 2025-07-03T01:55:24.879Z,1751507724.879 [DataOverHttps](INFO): SBD MOMSN=25309037 2025-07-03T01:55:54.122Z,1751507754.122 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250703T011033/Express0013.lzma 2025-07-03T01:56:05.175Z,1751507765.175 [DataOverHttps](INFO): Moved sent file to Logs/20250703T011033/Express0013.lzma.bak 2025-07-03T01:56:05.175Z,1751507765.175 [DataOverHttps](INFO): SBD MOMSN=25309160 2025-07-03T01:56:42.710Z,1751507802.710 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250703T011033/Express0016.lzma 2025-07-03T01:57:01.777Z,1751507821.777 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-07-03T01:57:10.576Z,1751507830.576 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250703T011033/Express0016.lzma 2025-07-03T01:57:22.627Z,1751507842.627 [DataOverHttps](INFO): Moved sent file to Logs/20250703T011033/Express0016.lzma.bak 2025-07-03T01:57:22.627Z,1751507842.627 [DataOverHttps](INFO): SBD MOMSN=25309162 2025-07-03T01:57:53.203Z,1751507873.203 [DataOverHttps](INFO): Sending 1406 bytes from file Logs/20250703T014613/Express0001.lzma 2025-07-03T01:58:04.263Z,1751507884.263 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Express0001.lzma.bak 2025-07-03T01:58:04.263Z,1751507884.263 [DataOverHttps](INFO): SBD MOMSN=25309165 2025-07-03T01:58:07.084Z,1751507887.084 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-07-03T01:58:07.089Z,1751507887.089 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-07-03T01:58:07.089Z,1751507887.089 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-03T01:58:48.933Z,1751507928.933 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:59:07.649Z,1751507947.649 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:59:26.469Z,1751507966.469 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T01:59:45.253Z,1751507985.253 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:00:04.045Z,1751508004.045 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:00:22.945Z,1751508022.945 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:00:41.685Z,1751508041.685 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:01:00.531Z,1751508060.531 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:01:19.245Z,1751508079.245 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:01:23.013Z,1751508083.013 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 654.71, 0.00 2025-07-03T02:01:27.848Z,1751508087.848 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-07-03T02:01:38.097Z,1751508098.097 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:01:55.929Z,1751508115.929 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:02:12.767Z,1751508132.767 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25070218032021,359, 0.0,1503.2, 0 2025-07-03T02:02:14.690Z,1751508134.690 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:02:36.369Z,1751508156.369 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:02:52.789Z,1751508172.789 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, -431, -197, +:WE, -431, -197, +233,A 2025-07-03T02:02:56.033Z,1751508176.033 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:03:02.902Z,1751508182.902 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, -92.59, +5.5 -7.91, 20.00, 0.00 2025-07-03T02:03:07.749Z,1751508187.749 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-03T02:03:07.750Z,1751508187.750 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-07-03T02:03:07.750Z,1751508187.750 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T02:03:07.750Z,1751508187.750 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-07-03T02:03:08.140Z,1751508188.140 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-07-03T02:03:08.141Z,1751508188.141 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-07-03T02:03:08.542Z,1751508188.542 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 15.011087 min 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:03:08.543Z,1751508188.543 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-07-03T02:03:10.554Z,1751508190.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020311.00,A,3654.28245,N,12206.86781,W,1.400,144.97,030725,,,A*7E 2025-07-03T02:03:10.556Z,1751508190.556 [NAL9602](INFO): GPS fix at 20250703T020311: (36.904708, -122.114463) 2025-07-03T02:03:10.568Z,1751508190.568 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-07-03T02:03:10.568Z,1751508190.568 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-07-03T02:03:21.556Z,1751508201.556 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250703T014613/Courier0004.lzma 2025-07-03T02:03:32.598Z,1751508212.598 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Courier0004.lzma.bak 2025-07-03T02:03:32.599Z,1751508212.599 [DataOverHttps](INFO): SBD MOMSN=25309193 2025-07-03T02:03:32.856Z,1751508212.856 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BS,-3268, 2025-07-03T02:04:00.712Z,1751508240.712 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250703T014613/Express0005.lzma 2025-07-03T02:04:08.041Z,1751508248.041 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, -108.96, -8.74, , 20.00, 0.00 2025-07-03T02:04:11.740Z,1751508251.740 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Express0005.lzma.bak 2025-07-03T02:04:11.741Z,1751508251.741 [DataOverHttps](INFO): SBD MOMSN=25309202 2025-07-03T02:04:16.143Z,1751508256.143 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-07-03T02:04:16.143Z,1751508256.143 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-07-03T02:04:16.143Z,1751508256.143 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-03T02:04:59.541Z,1751508299.541 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:05:18.289Z,1751508318.289 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:05:37.309Z,1751508337.309 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:05:56.129Z,1751508356.129 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:06:14.901Z,1751508374.901 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:06:37.017Z,1751508397.017 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:06:57.245Z,1751508417.245 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:07:16.109Z,1751508436.109 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:07:34.785Z,1751508454.785 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:07:54.541Z,1751508474.541 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:08:13.301Z,1751508493.301 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-03T02:08:13.330Z,1751508493.330 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:08:35.417Z,1751508515.417 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:08:44.010Z,1751508524.010 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-03T02:08:54.141Z,1751508534.141 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:09:12.953Z,1751508552.953 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:09:16.762Z,1751508556.762 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-03T02:09:16.763Z,1751508556.763 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-07-03T02:09:16.763Z,1751508556.763 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T02:09:16.763Z,1751508556.763 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-07-03T02:09:17.185Z,1751508557.185 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-07-03T02:09:17.185Z,1751508557.185 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-07-03T02:09:17.565Z,1751508557.565 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 21.161837 min 2025-07-03T02:09:17.565Z,1751508557.565 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-07-03T02:09:17.565Z,1751508557.565 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:09:17.565Z,1751508557.565 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-07-03T02:09:17.565Z,1751508557.565 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:09:17.566Z,1751508557.566 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3 2025-07-03T02:09:17.566Z,1751508557.566 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3 2025-07-03T02:09:17.566Z,1751508557.566 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:09:17.566Z,1751508557.566 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-07-03T02:09:19.557Z,1751508559.557 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020921.00,A,3654.22954,N,12206.83729,W,0.797,130.31,030725,,,A*72 2025-07-03T02:09:19.559Z,1751508559.559 [NAL9602](INFO): GPS fix at 20250703T020921: (36.903826, -122.113955) 2025-07-03T02:09:19.591Z,1751508559.591 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-07-03T02:09:19.591Z,1751508559.591 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-07-03T02:09:38.492Z,1751508578.492 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250703T014613/Courier0007.lzma 2025-07-03T02:09:50.323Z,1751508590.323 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-03T02:09:50.405Z,1751508590.405 [NAL9602](ERROR): received: +CSQ:0 OK 2025-07-03T02:09:50.542Z,1751508590.542 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Courier0007.lzma.bak 2025-07-03T02:09:50.543Z,1751508590.543 [DataOverHttps](INFO): SBD MOMSN=25309349 2025-07-03T02:10:29.912Z,1751508629.912 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-03T02:10:32.893Z,1751508632.893 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250703T014613/Express0008.lzma 2025-07-03T02:10:41.925Z,1751508641.925 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-07-03T02:10:54.673Z,1751508654.673 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250703T014613/Express0008.lzma 2025-07-03T02:11:09.731Z,1751508669.731 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Express0008.lzma.bak 2025-07-03T02:11:09.732Z,1751508669.732 [DataOverHttps](INFO): SBD MOMSN=25309352 2025-07-03T02:11:12.386Z,1751508672.386 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-07-03T02:11:12.386Z,1751508672.386 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-07-03T02:11:12.386Z,1751508672.386 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-03T02:12:10.074Z,1751508730.074 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.344822 2025-07-03T02:12:11.077Z,1751508731.077 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:12:32.230Z,1751508752.230 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003273 2025-07-03T02:12:33.229Z,1751508753.229 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:12:52.069Z,1751508772.069 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:13:10.837Z,1751508790.837 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:13:29.641Z,1751508809.641 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:13:32.669Z,1751508812.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=49117, MT Status=2, MTMSN=0 2025-07-03T02:13:32.669Z,1751508812.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-03T02:13:48.485Z,1751508828.485 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:14:07.277Z,1751508847.277 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:14:22.007Z,1751508862.007 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-03T02:14:29.365Z,1751508869.365 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:14:50.117Z,1751508890.117 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:14:52.478Z,1751508892.478 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-03T02:15:09.061Z,1751508909.061 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:15:29.913Z,1751508929.913 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:15:48.917Z,1751508948.917 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:16:07.654Z,1751508967.654 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:16:12.981Z,1751508972.981 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-03T02:16:12.981Z,1751508972.981 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-07-03T02:16:12.981Z,1751508972.981 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T02:16:12.981Z,1751508972.981 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-07-03T02:16:13.370Z,1751508973.370 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-07-03T02:16:13.370Z,1751508973.370 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-07-03T02:16:14.124Z,1751508974.124 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 28.098246 min 2025-07-03T02:16:14.124Z,1751508974.124 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-07-03T02:16:14.163Z,1751508974.163 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:16:14.163Z,1751508974.163 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-07-03T02:16:14.163Z,1751508974.163 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:16:14.164Z,1751508974.164 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4 2025-07-03T02:16:14.164Z,1751508974.164 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4 2025-07-03T02:16:14.164Z,1751508974.164 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:16:14.164Z,1751508974.164 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-07-03T02:16:15.889Z,1751508975.889 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021617.00,A,3654.18058,N,12206.79962,W,0.875,127.56,030725,,,A*75 2025-07-03T02:16:15.891Z,1751508975.891 [NAL9602](INFO): GPS fix at 20250703T021617: (36.903010, -122.113327) 2025-07-03T02:16:16.032Z,1751508976.032 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-07-03T02:16:16.032Z,1751508976.032 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-07-03T02:16:18.916Z,1751508978.916 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250703T014613/Courier0010.lzma 2025-07-03T02:16:29.970Z,1751508989.970 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Courier0010.lzma.bak 2025-07-03T02:16:29.971Z,1751508989.971 [DataOverHttps](INFO): SBD MOMSN=25309383 2025-07-03T02:16:46.458Z,1751509006.458 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-03T02:16:46.541Z,1751509006.541 [NAL9602](ERROR): received: +CSQ:1 OK117, 2, 0, 0, 0 OK 2025-07-03T02:16:57.105Z,1751509017.105 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20250703T014613/Express0011.lzma 2025-07-03T02:17:08.146Z,1751509028.146 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Express0011.lzma.bak 2025-07-03T02:17:08.147Z,1751509028.147 [DataOverHttps](INFO): SBD MOMSN=25309496 2025-07-03T02:17:11.175Z,1751509031.175 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-07-03T02:17:11.175Z,1751509031.175 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-07-03T02:17:11.175Z,1751509031.175 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-03T02:17:28.540Z,1751509048.540 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 4. 2025-07-03T02:17:28.543Z,1751509048.543 [BPC1](CRITICAL): Battery Capacity Below Threshold. 34.369000 Ah. 2025-07-03T02:17:28.544Z,1751509048.544 [BPC1](CRITICAL): Battery Voltage Below Threshold. 13.481549 V. 2025-07-03T02:17:28.544Z,1751509048.544 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-07-03T02:17:28.643Z,1751509048.643 [CBIT](INFO): Critical error at 20250703T021728 2025-07-03T02:17:48.845Z,1751509068.845 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:18:07.557Z,1751509087.557 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:18:29.877Z,1751509109.877 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:18:48.570Z,1751509128.570 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:19:08.997Z,1751509148.997 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:19:27.805Z,1751509167.805 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:19:46.589Z,1751509186.589 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:20:12.377Z,1751509212.377 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:20:35.561Z,1751509235.561 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:20:54.289Z,1751509254.289 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:21:12.953Z,1751509272.953 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:21:14.095Z,1751509274.095 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, +208, -10A 2025-07-03T02:21:18.110Z,1751509278.110 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-03T02:21:23.805Z,1751509283.805 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, -813, +71,A 2025-07-03T02:21:31.681Z,1751509291.681 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:21:33.881Z,1751509293.881 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, -117, -1,A 2025-07-03T02:21:48.814Z,1751509308.814 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-03T02:21:49.409Z,1751509309.409 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:22:03.799Z,1751509323.799 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -52. +6.85, +7.97, 0.00, 10.00 2025-07-03T02:22:08.165Z,1751509328.165 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:22:12.057Z,1751509332.057 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-03T02:22:12.057Z,1751509332.057 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-07-03T02:22:12.057Z,1751509332.057 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T02:22:12.057Z,1751509332.057 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-07-03T02:22:12.321Z,1751509332.321 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-07-03T02:22:12.321Z,1751509332.321 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-07-03T02:22:12.754Z,1751509332.754 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 34.080764 min 2025-07-03T02:22:12.754Z,1751509332.754 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-07-03T02:22:12.754Z,1751509332.754 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:22:12.754Z,1751509332.754 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-07-03T02:22:12.754Z,1751509332.754 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:22:12.754Z,1751509332.754 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #5 2025-07-03T02:22:12.754Z,1751509332.754 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=5 2025-07-03T02:22:12.755Z,1751509332.755 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:22:12.755Z,1751509332.755 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-07-03T02:22:13.938Z,1751509333.938 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768, 2025-07-03T02:22:14.712Z,1751509334.712 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022216.00,A,3654.13067,N,12206.76329,W,0.991,208.54,030725,,,D*7C 2025-07-03T02:22:14.715Z,1751509334.715 [NAL9602](INFO): GPS fix at 20250703T022216: (36.902178, -122.112722) 2025-07-03T02:22:14.794Z,1751509334.794 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-07-03T02:22:14.794Z,1751509334.794 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-07-03T02:22:19.307Z,1751509339.307 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20250703T014613/Courier0013.lzma 2025-07-03T02:22:30.354Z,1751509350.354 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Courier0013.lzma.bak 2025-07-03T02:22:30.355Z,1751509350.355 [DataOverHttps](INFO): SBD MOMSN=25309506 2025-07-03T02:22:45.403Z,1751509365.403 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-03T02:22:45.481Z,1751509365.481 [NAL9602](ERROR): received: +CSQ:0 OK117, 2, 0, 0, 0 OK 2025-07-03T02:23:00.029Z,1751509380.029 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250703T014613/Express0014.lzma 2025-07-03T02:23:11.071Z,1751509391.070 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Express0014.lzma.bak 2025-07-03T02:23:11.071Z,1751509391.071 [DataOverHttps](INFO): SBD MOMSN=25309511 2025-07-03T02:23:13.767Z,1751509393.767 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-07-03T02:23:13.767Z,1751509393.767 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-07-03T02:23:13.767Z,1751509393.767 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-03T02:23:17.311Z,1751509397.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=49117, MT Status=2, MTMSN=0 2025-07-03T02:23:17.311Z,1751509397.311 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-03T02:23:37.511Z,1751509417.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=49117, MT Status=0, MTMSN=0 2025-07-03T02:23:37.512Z,1751509417.512 [NAL9602](INFO): No messages in MT queue 2025-07-03T02:23:52.705Z,1751509432.705 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:24:08.215Z,1751509448.215 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-03T02:24:11.489Z,1751509451.489 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:24:33.557Z,1751509473.557 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:24:52.465Z,1751509492.465 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:25:10.207Z,1751509510.207 [DataOverHttps](IMPORTANT): SBD MTMSN=20250703T022511 2025-07-03T02:25:17.917Z,1751509517.917 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.MissionTimeout 10 h;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.903 degree;set keepstation.Longitude -122.11055 degree;set keepstation.Depth 25 m;run 2025-07-03T02:25:17.947Z,1751509517.947 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-07-03T02:25:17.947Z,1751509517.947 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-07-03T02:25:17.947Z,1751509517.947 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-07-03T02:25:17.952Z,1751509517.952 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-07-03T02:25:19.133Z,1751509519.133 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2025-07-03T02:25:19.141Z,1751509519.141 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-07-03T02:25:19.144Z,1751509519.144 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-07-03T02:25:19.147Z,1751509519.147 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-07-03T02:25:19.150Z,1751509519.150 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-07-03T02:25:19.153Z,1751509519.153 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-07-03T02:25:19.156Z,1751509519.156 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-07-03T02:25:19.159Z,1751509519.159 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-07-03T02:25:19.162Z,1751509519.162 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-07-03T02:25:19.165Z,1751509519.165 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-07-03T02:25:19.168Z,1751509519.168 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-07-03T02:25:19.171Z,1751509519.171 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-07-03T02:25:19.174Z,1751509519.174 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-07-03T02:25:19.197Z,1751509519.197 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-07-03T02:25:19.200Z,1751509519.200 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-07-03T02:25:19.214Z,1751509519.214 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-07-03T02:25:19.217Z,1751509519.217 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-07-03T02:25:19.220Z,1751509519.220 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-07-03T02:25:19.232Z,1751509519.232 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-07-03T02:25:19.239Z,1751509519.239 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-07-03T02:25:19.258Z,1751509519.258 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-07-03T02:25:19.258Z,1751509519.258 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-07-03T02:25:19.259Z,1751509519.259 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-07-03T02:25:19.260Z,1751509519.260 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-07-03T02:25:19.677Z,1751509519.677 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-07-03T02:25:19.684Z,1751509519.684 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-07-03T02:25:19.695Z,1751509519.695 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-07-03T02:25:19.707Z,1751509519.707 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-07-03T02:25:19.738Z,1751509519.738 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-07-03T02:25:19.793Z,1751509519.793 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-07-03T02:25:19.796Z,1751509519.796 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-07-03T02:25:19.807Z,1751509519.807 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-07-03T02:25:19.815Z,1751509519.815 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-07-03T02:25:19.843Z,1751509519.843 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-03T02:25:19.925Z,1751509519.925 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-07-03T02:25:19.925Z,1751509519.925 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-07-03T02:25:19.927Z,1751509519.927 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-07-03T02:25:20.071Z,1751509520.071 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-07-03T02:25:20.080Z,1751509520.080 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-07-03T02:25:20.087Z,1751509520.087 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-07-03T02:25:20.094Z,1751509520.094 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-07-03T02:25:20.101Z,1751509520.101 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-07-03T02:25:20.103Z,1751509520.103 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-07-03T02:25:20.125Z,1751509520.125 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-07-03T02:25:20.143Z,1751509520.143 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-07-03T02:25:20.197Z,1751509520.197 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-07-03T02:25:20.197Z,1751509520.197 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-07-03T02:25:20.198Z,1751509520.198 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-07-03T02:25:20.262Z,1751509520.262 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-07-03T02:25:20.280Z,1751509520.280 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-07-03T02:25:20.284Z,1751509520.284 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-07-03T02:25:20.291Z,1751509520.291 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-07-03T02:25:20.291Z,1751509520.291 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-07-03T02:25:20.297Z,1751509520.297 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-07-03T02:25:20.489Z,1751509520.489 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-07-03T02:25:20.494Z,1751509520.494 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-07-03T02:25:20.505Z,1751509520.505 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-07-03T02:25:20.508Z,1751509520.508 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-07-03T02:25:20.522Z,1751509520.522 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-07-03T02:25:20.526Z,1751509520.526 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool 2025-07-03T02:25:20.546Z,1751509520.546 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-07-03T02:25:20.548Z,1751509520.548 [keepstation:H.Pitch](DEBUG): Construct. 2025-07-03T02:25:20.641Z,1751509520.641 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-07-03T02:25:20.650Z,1751509520.650 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-07-03T02:25:20.660Z,1751509520.660 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-07-03T02:25:20.708Z,1751509520.708 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-07-03T02:25:20.726Z,1751509520.726 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-07-03T02:25:20.742Z,1751509520.742 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-07-03T02:25:20.763Z,1751509520.763 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-07-03T02:25:20.778Z,1751509520.778 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-07-03T02:25:20.803Z,1751509520.803 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-07-03T02:25:20.835Z,1751509520.835 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-07-03T02:25:20.866Z,1751509520.866 [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 keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call priorityHere=false refId="NeedComms" aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" priorityHere=false refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-07-03T02:25:20.866Z,1751509520.866 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-07-03T02:25:29.961Z,1751509529.961 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:25:40.805Z,1751509540.805 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 10 hour 2025-07-03T02:25:40.806Z,1751509540.806 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute 2025-07-03T02:25:40.807Z,1751509540.807 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.903 degree 2025-07-03T02:25:40.808Z,1751509540.808 [CommandExec](IMPORTANT): got command set keepstation.Longitude -122.11055 degree 2025-07-03T02:25:40.813Z,1751509540.813 [CommandExec](IMPORTANT): got command set keepstation.Depth 25 meter 2025-07-03T02:25:40.813Z,1751509540.813 [CommandExec](IMPORTANT): got command run 2025-07-03T02:25:40.816Z,1751509540.816 [CommandExec](IMPORTANT): Running 2025-07-03T02:25:40.818Z,1751509540.818 [DefaultWithUndock] Stopped 2025-07-03T02:25:40.818Z,1751509540.818 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2025-07-03T02:25:40.818Z,1751509540.818 [DefaultWithUndock:SurfaceDefault] Stopped 2025-07-03T02:25:40.818Z,1751509540.818 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2025-07-03T02:25:40.818Z,1751509540.818 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped 2025-07-03T02:25:40.818Z,1751509540.818 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-03T02:25:40.818Z,1751509540.818 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-07-03T02:25:40.819Z,1751509540.819 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-07-03T02:25:40.819Z,1751509540.819 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-07-03T02:25:40.819Z,1751509540.819 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T02:25:40.819Z,1751509540.819 [MissionManager](IMPORTANT): Started mission keepstation 2025-07-03T02:25:40.819Z,1751509540.819 [keepstation] Running Loop=1 2025-07-03T02:25:40.819Z,1751509540.819 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-07-03T02:25:40.819Z,1751509540.819 [keepstation:StandardEnvelopes] Running Loop=1 2025-07-03T02:25:40.819Z,1751509540.819 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-07-03T02:25:40.819Z,1751509540.819 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-07-03T02:25:40.820Z,1751509540.820 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-07-03T02:25:40.820Z,1751509540.820 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-07-03T02:25:40.820Z,1751509540.820 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-07-03T02:25:40.820Z,1751509540.820 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-07-03T02:25:40.820Z,1751509540.820 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-07-03T02:25:40.820Z,1751509540.820 [keepstation:BackseatDriver] Running Loop=1 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:PowerOnly] Running Loop=1 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:PowerOnly:A] Running Loop=1 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:PowerOnly:B] Running Loop=1 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:PowerOnly:C] Running Loop=1 2025-07-03T02:25:40.821Z,1751509540.821 [keepstation:PowerOnly:D] Running Loop=1 2025-07-03T02:25:40.822Z,1751509540.822 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-07-03T02:25:40.822Z,1751509540.822 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-07-03T02:25:40.822Z,1751509540.822 [keepstation:H.Pitch] Running Loop=1 2025-07-03T02:25:40.822Z,1751509540.822 [keepstation:H.Pitch](DEBUG): Initialize. 2025-07-03T02:25:40.822Z,1751509540.822 [keepstation:B] Running Loop=1 2025-07-03T02:25:40.823Z,1751509540.823 [keepstation:H.Pitch] Running Loop=1 2025-07-03T02:25:40.823Z,1751509540.823 [keepstation:PowerOnly] Running Loop=1 2025-07-03T02:25:40.824Z,1751509540.824 [keepstation:PowerOnly:D] Stopped 2025-07-03T02:25:40.824Z,1751509540.824 [keepstation:PowerOnly:C] Stopped 2025-07-03T02:25:40.824Z,1751509540.824 [keepstation:PowerOnly:B] Stopped 2025-07-03T02:25:40.824Z,1751509540.824 [keepstation:PowerOnly:A] Stopped 2025-07-03T02:25:40.824Z,1751509540.824 [keepstation:BackseatDriver] Running Loop=1 2025-07-03T02:25:40.824Z,1751509540.824 [keepstation:StandardEnvelopes] Running Loop=1 2025-07-03T02:25:40.824Z,1751509540.824 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-07-03T02:25:40.825Z,1751509540.825 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-07-03T02:25:40.826Z,1751509540.826 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-07-03T02:25:40.827Z,1751509540.827 [keepstation:B] Stopped 2025-07-03T02:25:40.827Z,1751509540.827 [keepstation:D] Running Loop=1 2025-07-03T02:25:41.203Z,1751509541.203 [keepstation:D] Stopped 2025-07-03T02:25:41.203Z,1751509541.203 [keepstation:E] Running Loop=1 2025-07-03T02:25:41.603Z,1751509541.603 [keepstation:E] Stopped 2025-07-03T02:25:41.603Z,1751509541.603 [keepstation:StartingMission] Running Loop=1 2025-07-03T02:25:41.603Z,1751509541.603 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-07-03T02:25:42.026Z,1751509542.026 [keepstation:SurfaceComms] Running Loop=1 2025-07-03T02:25:42.026Z,1751509542.026 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-07-03T02:25:42.027Z,1751509542.027 [keepstation:SurfaceComms:A] Running Loop=1 2025-07-03T02:25:42.027Z,1751509542.027 [keepstation:SurfaceComms:A] Stopped 2025-07-03T02:25:42.027Z,1751509542.027 [keepstation:SurfaceComms:B] Running Loop=1 2025-07-03T02:25:42.027Z,1751509542.027 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-07-03T02:25:42.422Z,1751509542.422 [keepstation:NeedComms] Running Loop=1 2025-07-03T02:25:42.422Z,1751509542.422 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-07-03T02:25:42.422Z,1751509542.422 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-07-03T02:25:42.422Z,1751509542.422 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-03T02:25:42.423Z,1751509542.423 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-07-03T02:25:42.423Z,1751509542.423 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-07-03T02:25:42.424Z,1751509542.424 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-07-03T02:25:42.424Z,1751509542.424 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-07-03T02:25:42.424Z,1751509542.424 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-03T02:25:42.425Z,1751509542.425 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-03T02:25:42.425Z,1751509542.425 [keepstation:NeedComms:A] Running Loop=1 2025-07-03T02:25:42.426Z,1751509542.426 [keepstation:NeedComms:A](INFO): last time_fix was: 1751509336.000000 second since 1970/01/01T00:00:00Z 2025-07-03T02:25:42.427Z,1751509542.427 [keepstation:NeedComms:A] Stopped 2025-07-03T02:25:42.816Z,1751509542.816 [keepstation:NeedComms:C] Running Loop=1 2025-07-03T02:25:43.220Z,1751509543.220 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-07-03T02:25:44.825Z,1751509544.825 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022546.00,A,3654.09919,N,12206.74354,W,1.633,175.76,030725,,,D*72 2025-07-03T02:25:44.827Z,1751509544.827 [NAL9602](INFO): GPS fix at 20250703T022546: (36.901653, -122.112392) 2025-07-03T02:25:44.840Z,1751509544.840 [keepstation:NeedComms:C] Stopped 2025-07-03T02:25:44.841Z,1751509544.841 [keepstation:NeedComms:D] Running Loop=1 2025-07-03T02:25:45.260Z,1751509545.260 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-03T02:26:00.449Z,1751509560.449 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250703T014613/Courier0016.lzma 2025-07-03T02:26:11.491Z,1751509571.491 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Courier0016.lzma.bak 2025-07-03T02:26:11.491Z,1751509571.491 [DataOverHttps](INFO): SBD MOMSN=25309524 2025-07-03T02:26:20.863Z,1751509580.863 [NAL9602](INFO): SBD MO Status=2, MOMSN=49118, MT Status=2, MTMSN=0 2025-07-03T02:26:20.864Z,1751509580.864 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-03T02:26:45.730Z,1751509605.730 [DataOverHttps](INFO): Sending 389 bytes from file Logs/20250703T014613/Express0017.lzma 2025-07-03T02:26:56.759Z,1751509616.759 [DataOverHttps](INFO): Moved sent file to Logs/20250703T014613/Express0017.lzma.bak 2025-07-03T02:26:56.759Z,1751509616.759 [DataOverHttps](INFO): SBD MOMSN=25309527 2025-07-03T02:26:59.269Z,1751509619.269 [keepstation:NeedComms:D] Stopped 2025-07-03T02:26:59.270Z,1751509619.270 [keepstation:NeedComms:E] Running Loop=1 2025-07-03T02:26:59.681Z,1751509619.681 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-07-03T02:27:39.194Z,1751509659.194 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:27:58.109Z,1751509678.109 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:28:16.873Z,1751509696.873 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-07-03T02:28:36.782Z,1751509716.782 [DataOverHttps](IMPORTANT): SBD MTMSN=20250703T022837 2025-07-03T02:28:45.957Z,1751509725.957 [DataOverHttps](INFO): Received command: configSet IBIT.batteryCapacityThreshold -1 ampere_hour persist;configSet IBIT.batteryVoltageThreshold 10 volt persist; restart app 2025-07-03T02:28:47.487Z,1751509727.487 [CommandExec](IMPORTANT): got command configSet IBIT.batteryCapacityThreshold -1 ampere_hour persist 2025-07-03T02:28:47.488Z,1751509727.488 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown. 2025-07-03T02:28:47.492Z,1751509727.492 [CommandExec](IMPORTANT): got command configSet IBIT.batteryVoltageThreshold 10 volt persist 2025-07-03T02:28:47.549Z,1751509727.549 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown. 2025-07-03T02:28:47.550Z,1751509727.550 [CommandExec](IMPORTANT): got command restart application 2025-07-03T02:28:48.553Z,1751509728.553 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-07-03T02:28:48.553Z,1751509728.553 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:48.553Z,1751509728.553 [CommandExec](INFO): Uninitializing the command executive. 2025-07-03T02:28:48.553Z,1751509728.553 [CommandExec](INFO): Uninitializing the command scheduler. 2025-07-03T02:28:48.553Z,1751509728.553 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:48.554Z,1751509728.554 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-07-03T02:28:48.554Z,1751509728.554 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-07-03T02:28:48.554Z,1751509728.554 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:48.555Z,1751509728.555 [NavChartDb](INFO): Join timeout helper Thread ID is 1121 2025-07-03T02:28:48.736Z,1751509728.736 [MissionManager](INFO): MissionManager is completed. 2025-07-03T02:28:48.736Z,1751509728.736 [MissionManager](INFO): Uninitializing Mission keepstation 2025-07-03T02:28:48.736Z,1751509728.736 [keepstation] Stopped 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:NeedComms] Stopped 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:NeedComms:E] Stopped 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:StandardEnvelopes] Stopped 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-07-03T02:28:48.737Z,1751509728.737 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:BackseatDriver] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:PowerOnly] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:PowerOnly:E.Wait] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:H.Pitch] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:SurfaceComms] Stopped 2025-07-03T02:28:48.738Z,1751509728.738 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-07-03T02:28:48.739Z,1751509728.739 [keepstation:SurfaceComms:B] Stopped 2025-07-03T02:28:48.739Z,1751509728.739 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-07-03T02:28:48.739Z,1751509728.739 [keepstation:StartingMission] Stopped 2025-07-03T02:28:48.739Z,1751509728.739 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-07-03T02:28:48.841Z,1751509728.841 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:48.841Z,1751509728.841 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:48.861Z,1751509728.861 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2025-07-03T02:28:48.861Z,1751509728.861 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:48.861Z,1751509728.861 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1122 2025-07-03T02:28:48.969Z,1751509728.969 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:48.969Z,1751509728.969 [WetLabsUBAT](INFO): Powering down 2025-07-03T02:28:48.970Z,1751509728.970 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:48.973Z,1751509728.973 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-07-03T02:28:48.973Z,1751509728.973 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:48.973Z,1751509728.973 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1123 2025-07-03T02:28:49.125Z,1751509729.125 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:49.126Z,1751509729.126 [WetLabsBB2FL](INFO): Powering down 2025-07-03T02:28:49.126Z,1751509729.126 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:49.128Z,1751509729.128 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-07-03T02:28:49.161Z,1751509729.161 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:49.161Z,1751509729.161 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1124 2025-07-03T02:28:49.214Z,1751509729.214 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-07-03T02:28:49.214Z,1751509729.214 [DefaultWithUndock] Running Loop=1 2025-07-03T02:28:49.214Z,1751509729.214 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-07-03T02:28:49.214Z,1751509729.214 [DefaultWithUndock:A.Wait] Running Loop=1 2025-07-03T02:28:49.215Z,1751509729.215 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-07-03T02:28:49.933Z,1751509729.933 [CTD_Seabird](INFO): Powering down 2025-07-03T02:28:49.945Z,1751509729.945 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:49.945Z,1751509729.945 [CTD_Seabird](INFO): Powering down 2025-07-03T02:28:49.957Z,1751509729.957 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:49.977Z,1751509729.977 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-07-03T02:28:49.977Z,1751509729.977 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:49.977Z,1751509729.977 [Radio_Surface](INFO): Join timeout helper Thread ID is 1126 2025-07-03T02:28:50.077Z,1751509730.077 [Radio_Surface](INFO): Powering down 2025-07-03T02:28:50.078Z,1751509730.078 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:50.078Z,1751509730.078 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:50.097Z,1751509730.097 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-07-03T02:28:50.098Z,1751509730.098 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:50.098Z,1751509730.098 [Onboard](INFO): Join timeout helper Thread ID is 1127 2025-07-03T02:28:54.125Z,1751509734.125 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:54.125Z,1751509734.125 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:54.137Z,1751509734.137 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-07-03T02:28:54.137Z,1751509734.137 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:54.138Z,1751509734.138 [DataOverHttps](INFO): Join timeout helper Thread ID is 1128 2025-07-03T02:28:54.989Z,1751509734.989 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:54.992Z,1751509734.992 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.005Z,1751509735.005 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-07-03T02:28:55.005Z,1751509735.005 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.006Z,1751509735.006 [DAT](INFO): Join timeout helper Thread ID is 1129 2025-07-03T02:28:55.225Z,1751509735.225 [DAT](INFO): Powering down 2025-07-03T02:28:55.226Z,1751509735.226 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:55.226Z,1751509735.226 [DAT](INFO): Powering down 2025-07-03T02:28:55.227Z,1751509735.227 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.229Z,1751509735.229 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-07-03T02:28:55.230Z,1751509735.230 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.230Z,1751509735.230 [BackseatComponent](INFO): Join timeout helper Thread ID is 1130 2025-07-03T02:28:55.281Z,1751509735.281 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:55.281Z,1751509735.281 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.290Z,1751509735.290 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-07-03T02:28:55.290Z,1751509735.290 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.290Z,1751509735.290 [logger](INFO): Join timeout helper Thread ID is 1131 2025-07-03T02:28:55.293Z,1751509735.293 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:55.294Z,1751509735.294 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.295Z,1751509735.295 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-07-03T02:28:55.295Z,1751509735.295 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.295Z,1751509735.295 [CommandLine](INFO): Join timeout helper Thread ID is 1132 2025-07-03T02:28:55.349Z,1751509735.349 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:55.349Z,1751509735.349 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.353Z,1751509735.353 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-07-03T02:28:55.353Z,1751509735.353 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.354Z,1751509735.354 [CommandExec](INFO): Join timeout helper Thread ID is 1133 2025-07-03T02:28:55.355Z,1751509735.355 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-07-03T02:28:55.355Z,1751509735.355 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.356Z,1751509735.356 [controlThread](INFO): Join timeout helper Thread ID is 1134 2025-07-03T02:28:55.573Z,1751509735.573 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-03T02:28:55.573Z,1751509735.573 [controlThread](DEBUG): Uninitializing ControlThread 2025-07-03T02:28:55.574Z,1751509735.574 [AHRS_M2](INFO): Powering down 2025-07-03T02:28:55.577Z,1751509735.577 [NAL9602](INFO): Powering down 2025-07-03T02:28:55.578Z,1751509735.578 [RDI_Pathfinder](INFO): Powering down 2025-07-03T02:28:55.579Z,1751509735.579 [Sonardyne_Nano](INFO): Powering down 2025-07-03T02:28:55.581Z,1751509735.581 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-07-03T02:28:55.582Z,1751509735.582 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-07-03T02:28:55.582Z,1751509735.582 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-07-03T02:28:55.583Z,1751509735.583 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2025-07-03T02:28:55.583Z,1751509735.583 [DefaultWithUndock] Stopped 2025-07-03T02:28:55.583Z,1751509735.583 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2025-07-03T02:28:55.583Z,1751509735.583 [DefaultWithUndock:A.Wait] Stopped 2025-07-03T02:28:55.583Z,1751509735.583 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-07-03T02:28:55.592Z,1751509735.592 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-07-03T02:28:55.592Z,1751509735.592 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-07-03T02:28:55.593Z,1751509735.593 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-07-03T02:28:55.593Z,1751509735.593 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-07-03T02:28:55.593Z,1751509735.593 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-07-03T02:28:55.594Z,1751509735.594 [BuoyancyServo](INFO): Powering down 2025-07-03T02:28:55.605Z,1751509735.605 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-07-03T02:28:55.605Z,1751509735.605 [ElevatorServo](INFO): Powering down 2025-07-03T02:28:55.606Z,1751509735.606 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-07-03T02:28:55.606Z,1751509735.606 [DockingStepper](INFO): Powering down 2025-07-03T02:28:55.607Z,1751509735.607 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-07-03T02:28:55.607Z,1751509735.607 [MassServo](INFO): Powering down 2025-07-03T02:28:55.608Z,1751509735.608 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-07-03T02:28:55.608Z,1751509735.608 [RudderServo](INFO): Powering down 2025-07-03T02:28:55.609Z,1751509735.609 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2025-07-03T02:28:55.609Z,1751509735.609 [ThrusterServo](INFO): Powering down 2025-07-03T02:28:55.610Z,1751509735.610 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-07-03T02:28:55.610Z,1751509735.610 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-07-03T02:28:55.610Z,1751509735.610 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-07-03T02:28:55.610Z,1751509735.610 [CBIT](DEBUG): Powering off loads. 2025-07-03T02:28:55.624Z,1751509735.624 [CBIT](DEBUG): Disabling WDT. 2025-07-03T02:28:55.637Z,1751509735.637 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-03T02:28:55.637Z,1751509735.637 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-03T02:28:55.638Z,1751509735.638 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.708Z,1751509735.708 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.711Z,1751509735.711 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.718Z,1751509735.718 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.724Z,1751509735.724 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.768Z,1751509735.768 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.806Z,1751509735.806 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-03T02:28:55.806Z,1751509735.806 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-03T02:28:55.837Z,1751509735.837 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.839Z,1751509735.839 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.844Z,1751509735.844 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.852Z,1751509735.852 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-03T02:28:55.932Z,1751509735.932 [logger ThreadHandler](INFO): Thread cancelled.