2025-06-05T21:05:25.928Z,1749157525.928 [Supervisor](DEBUG): Initializing supervisor. 2025-06-05T21:05:25.932Z,1749157525.932 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-06-05T21:05:25.933Z,1749157525.933 [SyncHandler](INFO): Protected caller Thread ID is 839 2025-06-05T21:05:25.933Z,1749157525.933 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-06-05T21:05:25.934Z,1749157525.934 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-06-05T21:05:25.934Z,1749157525.934 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 840 2025-06-05T21:05:25.939Z,1749157525.939 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-06-05T21:05:25.960Z,1749157525.960 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-06-05T21:05:25.961Z,1749157525.961 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-06-05T21:05:25.961Z,1749157525.961 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 841 2025-06-05T21:05:25.966Z,1749157525.966 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-06-05T21:05:25.967Z,1749157525.967 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-06-05T21:05:25.968Z,1749157525.968 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 842 2025-06-05T21:05:25.970Z,1749157525.970 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-06-05T21:05:25.971Z,1749157525.971 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-06-05T21:05:25.971Z,1749157525.971 [logger ThreadHandler](INFO): Protected caller Thread ID is 843 2025-06-05T21:05:25.975Z,1749157525.975 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-06-05T21:05:25.976Z,1749157525.976 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-06-05T21:05:25.980Z,1749157525.980 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-06-05T21:05:26.087Z,1749157526.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-06-05T21:05:26.088Z,1749157526.088 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-06-05T21:05:26.865Z,1749157526.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-06-05T21:05:26.867Z,1749157526.867 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-06-05T21:05:27.120Z,1749157527.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-06-05T21:05:27.122Z,1749157527.122 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-06-05T21:05:27.212Z,1749157527.212 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-06-05T21:05:27.370Z,1749157527.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-06-05T21:05:27.371Z,1749157527.371 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-06-05T21:05:27.474Z,1749157527.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-06-05T21:05:27.476Z,1749157527.476 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-06-05T21:05:27.597Z,1749157527.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-06-05T21:05:27.598Z,1749157527.598 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-06-05T21:05:28.030Z,1749157528.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-06-05T21:05:28.032Z,1749157528.032 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-06-05T21:05:28.594Z,1749157528.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-06-05T21:05:28.604Z,1749157528.604 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-06-05T21:05:28.846Z,1749157528.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-06-05T21:05:28.847Z,1749157528.847 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-06-05T21:05:29.049Z,1749157529.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-06-05T21:05:29.051Z,1749157529.051 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-06-05T21:05:29.637Z,1749157529.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-06-05T21:05:29.638Z,1749157529.638 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-06-05T21:05:30.718Z,1749157530.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-06-05T21:05:30.720Z,1749157530.720 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-06-05T21:05:30.975Z,1749157530.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-06-05T21:05:30.977Z,1749157530.977 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-06-05T21:05:31.380Z,1749157531.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-06-05T21:05:31.381Z,1749157531.381 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-06-05T21:05:31.755Z,1749157531.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-06-05T21:05:31.757Z,1749157531.757 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2025-06-05T21:05:31.759Z,1749157531.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2025-06-05T21:05:31.853Z,1749157531.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2025-06-05T21:05:32.036Z,1749157532.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg 2025-06-05T21:05:32.153Z,1749157532.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2025-06-05T21:05:32.259Z,1749157532.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2025-06-05T21:05:32.381Z,1749157532.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2025-06-05T21:05:32.478Z,1749157532.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2025-06-05T21:05:32.586Z,1749157532.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2025-06-05T21:05:32.712Z,1749157532.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2025-06-05T21:05:32.995Z,1749157532.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-06-05T21:05:32.995Z,1749157532.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2025-06-05T21:05:33.090Z,1749157533.090 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2025-06-05T21:05:33.194Z,1749157533.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2025-06-05T21:05:33.349Z,1749157533.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2025-06-05T21:05:33.456Z,1749157533.456 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-06-05T21:05:33.473Z,1749157533.473 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-06-05T21:05:33.655Z,1749157533.655 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-06-05T21:05:33.655Z,1749157533.655 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-06-05T21:05:33.730Z,1749157533.730 [VerticalControl](DEBUG): Construct VerticalControl. 2025-06-05T21:05:33.795Z,1749157533.795 [VerticalControl] Loaded 2025-06-05T21:05:33.795Z,1749157533.795 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-06-05T21:05:33.798Z,1749157533.798 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-06-05T21:05:33.847Z,1749157533.847 [HorizontalControl] Loaded 2025-06-05T21:05:33.847Z,1749157533.847 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-06-05T21:05:33.850Z,1749157533.850 [SpeedControl](DEBUG): Construct SpeedControl. 2025-06-05T21:05:33.853Z,1749157533.853 [SpeedControl] Loaded 2025-06-05T21:05:33.853Z,1749157533.853 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-06-05T21:05:33.856Z,1749157533.856 [LoopControl](DEBUG): Construct LoopControl. 2025-06-05T21:05:33.856Z,1749157533.856 [LoopControl] Loaded 2025-06-05T21:05:33.857Z,1749157533.857 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-06-05T21:05:33.857Z,1749157533.857 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-06-05T21:05:33.858Z,1749157533.858 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-06-05T21:05:33.933Z,1749157533.933 [DepthRateCalculator] Loaded 2025-06-05T21:05:33.933Z,1749157533.933 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-06-05T21:05:33.938Z,1749157533.938 [PitchRateCalculator] Loaded 2025-06-05T21:05:33.938Z,1749157533.938 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-06-05T21:05:33.949Z,1749157533.949 [SpeedCalculator] Loaded 2025-06-05T21:05:33.949Z,1749157533.949 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-06-05T21:05:33.954Z,1749157533.954 [YawRateCalculator] Loaded 2025-06-05T21:05:33.954Z,1749157533.954 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-06-05T21:05:33.973Z,1749157533.973 [ElevatorOffsetCalculator] Loaded 2025-06-05T21:05:33.974Z,1749157533.974 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-06-05T21:05:33.974Z,1749157533.974 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-06-05T21:05:33.976Z,1749157533.976 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-06-05T21:05:34.027Z,1749157534.027 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-06-05T21:05:34.027Z,1749157534.027 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-06-05T21:05:34.120Z,1749157534.120 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-06-05T21:05:34.120Z,1749157534.120 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-06-05T21:05:34.554Z,1749157534.554 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-06-05T21:05:34.554Z,1749157534.554 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-06-05T21:05:35.252Z,1749157535.252 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-06-05T21:05:35.253Z,1749157535.253 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-06-05T21:05:36.299Z,1749157536.299 [AHRS_M2] Loaded 2025-06-05T21:05:36.300Z,1749157536.300 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-06-05T21:05:36.554Z,1749157536.554 [BackseatComponent] Loaded 2025-06-05T21:05:36.554Z,1749157536.554 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-06-05T21:05:36.555Z,1749157536.555 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409E84E0 2025-06-05T21:05:36.556Z,1749157536.556 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 932 2025-06-05T21:05:36.559Z,1749157536.559 [LcmUniversalReporter] Loaded 2025-06-05T21:05:36.559Z,1749157536.559 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-06-05T21:05:37.392Z,1749157537.392 [BPC1] Loaded 2025-06-05T21:05:37.392Z,1749157537.392 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-06-05T21:05:37.492Z,1749157537.492 [DAT] Loaded 2025-06-05T21:05:37.492Z,1749157537.492 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-06-05T21:05:37.494Z,1749157537.494 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A184E0 2025-06-05T21:05:37.494Z,1749157537.494 [DAT ThreadHandler](INFO): Protected caller Thread ID is 933 2025-06-05T21:05:37.643Z,1749157537.643 [DataOverHttps] Loaded 2025-06-05T21:05:37.643Z,1749157537.643 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-06-05T21:05:37.645Z,1749157537.645 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A484E0 2025-06-05T21:05:37.645Z,1749157537.645 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 934 2025-06-05T21:05:37.669Z,1749157537.669 [Depth_Keller] Loaded 2025-06-05T21:05:37.669Z,1749157537.669 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-06-05T21:05:37.736Z,1749157537.736 [NAL9602] Loaded 2025-06-05T21:05:37.736Z,1749157537.736 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-06-05T21:05:37.767Z,1749157537.767 [Onboard] Loaded 2025-06-05T21:05:37.768Z,1749157537.768 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-06-05T21:05:37.769Z,1749157537.769 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A784E0 2025-06-05T21:05:37.769Z,1749157537.769 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 935 2025-06-05T21:05:37.784Z,1749157537.784 [Power24vConverter] Loaded 2025-06-05T21:05:37.784Z,1749157537.784 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-06-05T21:05:37.800Z,1749157537.800 [Radio_Surface] Loaded 2025-06-05T21:05:37.801Z,1749157537.801 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-06-05T21:05:37.802Z,1749157537.802 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AA84E0 2025-06-05T21:05:37.802Z,1749157537.802 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 936 2025-06-05T21:05:37.851Z,1749157537.851 [RDI_Pathfinder] Loaded 2025-06-05T21:05:37.851Z,1749157537.851 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-06-05T21:05:37.867Z,1749157537.867 [Sonardyne_Nano] Loaded 2025-06-05T21:05:37.867Z,1749157537.867 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-06-05T21:05:37.868Z,1749157537.868 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-06-05T21:05:37.868Z,1749157537.868 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-06-05T21:05:38.013Z,1749157538.013 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-06-05T21:05:38.013Z,1749157538.013 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-06-05T21:05:38.028Z,1749157538.028 [NavChart] Loaded 2025-06-05T21:05:38.028Z,1749157538.028 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-06-05T21:05:38.034Z,1749157538.034 [UniversalFixResidualReporter] Loaded 2025-06-05T21:05:38.034Z,1749157538.034 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-06-05T21:05:38.035Z,1749157538.035 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-06-05T21:05:38.036Z,1749157538.036 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-06-05T21:05:38.247Z,1749157538.247 [SBIT](DEBUG): Construct Startup Built In Test. 2025-06-05T21:05:38.256Z,1749157538.256 [SBIT] Loaded 2025-06-05T21:05:38.256Z,1749157538.256 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-06-05T21:05:38.259Z,1749157538.259 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-06-05T21:05:38.273Z,1749157538.273 [IBIT] Loaded 2025-06-05T21:05:38.273Z,1749157538.273 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-06-05T21:05:38.279Z,1749157538.279 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-06-05T21:05:38.365Z,1749157538.365 [CBIT] Loaded 2025-06-05T21:05:38.365Z,1749157538.365 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-06-05T21:05:38.399Z,1749157538.399 [GFScanner] Loaded 2025-06-05T21:05:38.399Z,1749157538.399 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-06-05T21:05:38.400Z,1749157538.400 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-06-05T21:05:38.400Z,1749157538.400 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-06-05T21:05:38.577Z,1749157538.577 [BuoyancyServo] Loaded 2025-06-05T21:05:38.577Z,1749157538.577 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-06-05T21:05:38.601Z,1749157538.601 [ElevatorServo] Loaded 2025-06-05T21:05:38.602Z,1749157538.602 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-06-05T21:05:38.634Z,1749157538.634 [DockingStepper] Loaded 2025-06-05T21:05:38.634Z,1749157538.634 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-06-05T21:05:38.656Z,1749157538.656 [MassServo] Loaded 2025-06-05T21:05:38.657Z,1749157538.657 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-06-05T21:05:38.678Z,1749157538.678 [RudderServo] Loaded 2025-06-05T21:05:38.678Z,1749157538.678 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-06-05T21:05:38.698Z,1749157538.698 [ThrusterServo] Loaded 2025-06-05T21:05:38.699Z,1749157538.699 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2025-06-05T21:05:38.699Z,1749157538.699 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-06-05T21:05:38.700Z,1749157538.700 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-06-05T21:05:38.716Z,1749157538.716 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-06-05T21:05:38.717Z,1749157538.717 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-06-05T21:05:38.949Z,1749157538.949 [CTD_Seabird] Loaded 2025-06-05T21:05:38.949Z,1749157538.949 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-06-05T21:05:38.950Z,1749157538.950 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0A4E0 2025-06-05T21:05:38.951Z,1749157538.951 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 937 2025-06-05T21:05:38.974Z,1749157538.974 [PAR_Licor] Loaded 2025-06-05T21:05:38.974Z,1749157538.974 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-06-05T21:05:39.022Z,1749157539.022 [WetLabsBB2FL] Loaded 2025-06-05T21:05:39.022Z,1749157539.022 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-06-05T21:05:39.023Z,1749157539.023 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3A4E0 2025-06-05T21:05:39.023Z,1749157539.023 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 938 2025-06-05T21:05:39.046Z,1749157539.046 [WetLabsUBAT] Loaded 2025-06-05T21:05:39.046Z,1749157539.046 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2025-06-05T21:05:39.047Z,1749157539.047 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6A4E0 2025-06-05T21:05:39.047Z,1749157539.047 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 939 2025-06-05T21:05:39.048Z,1749157539.048 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-06-05T21:05:39.055Z,1749157539.055 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-06-05T21:05:39.058Z,1749157539.058 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-06-05T21:05:39.070Z,1749157539.070 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-06-05T21:05:39.071Z,1749157539.071 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9A4E0 2025-06-05T21:05:39.071Z,1749157539.071 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 940 2025-06-05T21:05:39.076Z,1749157539.076 [Supervisor](INFO): Main Thread ID is 830 2025-06-05T21:05:39.076Z,1749157539.076 [Supervisor](DEBUG): Running supervisor. 2025-06-05T21:05:39.077Z,1749157539.077 [CommandExec ThreadHandler](INFO): Handler Thread ID is 941 2025-06-05T21:05:39.077Z,1749157539.077 [CommandExec](INFO): Initializing the command executive. 2025-06-05T21:05:39.079Z,1749157539.079 [CommandLine ThreadHandler](INFO): Handler Thread ID is 942 2025-06-05T21:05:39.081Z,1749157539.081 [controlThread ThreadHandler](INFO): Handler Thread ID is 943 2025-06-05T21:05:39.081Z,1749157539.081 [controlThread](DEBUG): Initializing ControlThread 2025-06-05T21:05:39.082Z,1749157539.082 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-06-05T21:05:39.084Z,1749157539.084 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-06-05T21:05:39.085Z,1749157539.085 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-06-05T21:05:39.085Z,1749157539.085 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-06-05T21:05:39.085Z,1749157539.085 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-06-05T21:05:39.086Z,1749157539.086 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-06-05T21:05:39.086Z,1749157539.086 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-06-05T21:05:39.086Z,1749157539.086 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-06-05T21:05:39.087Z,1749157539.087 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-06-05T21:05:39.090Z,1749157539.090 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-06-05T21:05:39.090Z,1749157539.090 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-06-05T21:05:39.091Z,1749157539.091 [SBIT](INFO): Initialize SBIT Component. 2025-06-05T21:05:39.091Z,1749157539.091 [SBIT](IMPORTANT): git: 2025-06-02-8-gec96daa83 2025-06-05T21:05:39.091Z,1749157539.091 [SBIT](INFO): git hash: ec96daa838cb6ac1927da96e62739399b7e6a3fd 2025-06-05T21:05:39.092Z,1749157539.092 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-06-05T21:05:39.093Z,1749157539.093 [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-06-05T21:05:39.094Z,1749157539.094 [SBIT](INFO): Beginning SBIT in 96.000000 seconds. 2025-06-05T21:05:39.094Z,1749157539.094 [IBIT](INFO): Initialize IBIT Component. 2025-06-05T21:05:39.095Z,1749157539.095 [CBIT](DEBUG): Initialize CBIT Component. 2025-06-05T21:05:39.096Z,1749157539.096 [logger ThreadHandler](INFO): Handler Thread ID is 944 2025-06-05T21:05:39.108Z,1749157539.108 [CBIT](DEBUG): Initialized mux pins. 2025-06-05T21:05:39.108Z,1749157539.108 [CBIT](DEBUG): Initializing the watchdog timer. 2025-06-05T21:05:39.116Z,1749157539.116 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 945 2025-06-05T21:05:39.132Z,1749157539.132 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-06-05T21:05:39.132Z,1749157539.132 [CBIT](DEBUG): Initializing heartbeat. 2025-06-05T21:05:39.133Z,1749157539.133 [DAT ThreadHandler](INFO): Handler Thread ID is 946 2025-06-05T21:05:39.134Z,1749157539.134 [DAT](INFO): Powering up 2025-06-05T21:05:39.134Z,1749157539.134 [DAT](DEBUG): Initializing DAT. 2025-06-05T21:05:39.137Z,1749157539.137 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 948 2025-06-05T21:05:39.139Z,1749157539.139 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-06-05T21:05:39.160Z,1749157539.160 [Onboard ThreadHandler](INFO): Handler Thread ID is 949 2025-06-05T21:05:39.177Z,1749157539.177 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 950 2025-06-05T21:05:39.196Z,1749157539.196 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 951 2025-06-05T21:05:39.197Z,1749157539.197 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-06-05T21:05:39.201Z,1749157539.201 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 953 2025-06-05T21:05:39.204Z,1749157539.204 [CBIT](DEBUG): Deactivating emergency mode. 2025-06-05T21:05:39.208Z,1749157539.208 [WetLabsBB2FL](INFO): Powering up 2025-06-05T21:05:39.209Z,1749157539.209 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 955 2025-06-05T21:05:39.213Z,1749157539.213 [WetLabsUBAT](INFO): Powering up 2025-06-05T21:05:39.214Z,1749157539.214 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 957 2025-06-05T21:05:39.222Z,1749157539.222 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-06-05T21:05:39.222Z,1749157539.222 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-06-05T21:05:39.223Z,1749157539.223 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-06-05T21:05:39.223Z,1749157539.223 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-06-05T21:05:39.223Z,1749157539.223 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-06-05T21:05:39.223Z,1749157539.223 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-06-05T21:05:39.223Z,1749157539.223 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-06-05T21:05:39.223Z,1749157539.223 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-06-05T21:05:39.244Z,1749157539.244 [CBIT](DEBUG): Backplane powered. 2025-06-05T21:05:39.244Z,1749157539.244 [GFScanner](DEBUG): Initializing GFScanner 2025-06-05T21:05:39.244Z,1749157539.244 [GFScanner](DEBUG): Deactivating GF circuits. 2025-06-05T21:05:39.252Z,1749157539.252 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-06-05T21:05:39.253Z,1749157539.253 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2025-06-05T21:05:39.253Z,1749157539.253 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2025-06-05T21:05:39.268Z,1749157539.268 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2025-06-05T21:05:39.287Z,1749157539.287 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2025-06-05T21:05:39.288Z,1749157539.288 [MissionManager](ERROR): Top node has no child nodes: Mission 2025-06-05T21:05:39.288Z,1749157539.288 [MissionManager](ERROR): Could not load startup mission. 2025-06-05T21:05:39.288Z,1749157539.288 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2025-06-05T21:05:39.289Z,1749157539.289 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2025-06-05T21:05:39.293Z,1749157539.293 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2025-06-05T21:05:39.584Z,1749157539.584 [Radio_Surface](INFO): Powering up 2025-06-05T21:05:39.830Z,1749157539.830 [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-06-05T21:05:39.849Z,1749157539.849 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2025-06-05T21:05:39.853Z,1749157539.853 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2025-06-05T21:05:39.872Z,1749157539.872 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2025-06-05T21:05:39.889Z,1749157539.889 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2025-06-05T21:05:39.893Z,1749157539.893 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2025-06-05T21:05:39.905Z,1749157539.905 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2025-06-05T21:05:39.906Z,1749157539.906 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2025-06-05T21:05:40.080Z,1749157540.080 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2025-06-05T21:05:40.080Z,1749157540.080 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-05T21:05:40.137Z,1749157540.137 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-05T21:05:40.172Z,1749157540.172 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-06-05T21:05:40.175Z,1749157540.175 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-06-05T21:05:40.209Z,1749157540.209 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2025-06-05T21:05:40.214Z,1749157540.214 [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-06-05T21:05:40.225Z,1749157540.225 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,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-06-05T21:05:40.252Z,1749157540.252 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-06-05T21:05:40.416Z,1749157540.416 [Depth_Keller](INFO): Initializing. 2025-06-05T21:05:40.417Z,1749157540.417 [Power24vConverter](INFO): Powering up. 2025-06-05T21:05:40.418Z,1749157540.418 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-06-05T21:05:40.425Z,1749157540.425 [Sonardyne_Nano](INFO): Initializing. 2025-06-05T21:05:40.468Z,1749157540.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-06-05T21:05:40.499Z,1749157540.499 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-06-05T21:05:40.504Z,1749157540.504 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-06-05T21:05:40.505Z,1749157540.505 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-06-05T21:05:40.512Z,1749157540.512 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-06-05T21:05:40.513Z,1749157540.513 [DockingStepper](INFO): Start 2025-06-05T21:05:40.513Z,1749157540.513 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-06-05T21:05:40.520Z,1749157540.520 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-06-05T21:05:40.521Z,1749157540.521 [MassServo](DEBUG): Initializing EZServoServo. 2025-06-05T21:05:40.528Z,1749157540.528 [MassServo](DEBUG): Initializing MassServo. 2025-06-05T21:05:40.529Z,1749157540.529 [RudderServo](DEBUG): Initializing EZServoServo. 2025-06-05T21:05:40.536Z,1749157540.536 [RudderServo](DEBUG): Initializing RudderServo. 2025-06-05T21:05:40.537Z,1749157540.537 [ThrusterServo](DEBUG): Initializing EZServoServo. 2025-06-05T21:05:40.545Z,1749157540.545 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2025-06-05T21:05:40.714Z,1749157540.714 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m 2025-06-05T21:05:40.729Z,1749157540.729 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-06-05T21:05:40.744Z,1749157540.744 [DockingStepper](INFO): Starting 2025-06-05T21:05:41.059Z,1749157541.059 [DockingStepper](INFO): Starting 2025-06-05T21:05:41.584Z,1749157541.584 [DockingStepper](INFO): Starting 2025-06-05T21:05:41.633Z,1749157541.633 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2025-06-05T21:05:41.816Z,1749157541.816 [WetLabsBB2FL](INFO): Powering down 2025-06-05T21:05:41.888Z,1749157541.888 [DockingStepper](INFO): Starting 2025-06-05T21:05:42.289Z,1749157542.289 [DockingStepper](INFO): Starting 2025-06-05T21:05:42.674Z,1749157542.674 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-06-05T21:05:42.724Z,1749157542.724 [DockingStepper](INFO): Starting 2025-06-05T21:05:43.128Z,1749157543.128 [DockingStepper](INFO): Starting 2025-06-05T21:05:43.152Z,1749157543.152 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-06-05T21:05:43.628Z,1749157543.628 [DockingStepper](INFO): Starting 2025-06-05T21:05:43.913Z,1749157543.913 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103 2025-06-05T21:05:44.333Z,1749157544.333 [DockingStepper](INFO): Pause 2025-06-05T21:05:44.839Z,1749157544.839 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2025-06-05T21:05:44.841Z,1749157544.841 [Radio_Surface](INFO): No local IP found 2025-06-05T21:05:48.536Z,1749157548.536 [WetLabsUBAT](INFO): Powering down 2025-06-05T21:05:52.489Z,1749157552.489 [DAT](INFO): DAT read: 2025-06-05T21:05:52.490Z,1749157552.490 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-06-05T21:05:58.034Z,1749157558.034 [DAT](INFO): DAT read: MF Frequency Band 2025-06-05T21:05:58.037Z,1749157558.037 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2025-06-05T21:05:58.039Z,1749157558.039 [DAT](INFO): DAT read: Jun 5 2025 21:05:14 2025-06-05T21:05:59.293Z,1749157559.293 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-06-05T21:05:59.294Z,1749157559.294 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-06-05T21:05:59.295Z,1749157559.295 [DAT](INFO): commRate: 600 2025-06-05T21:06:01.366Z,1749157561.366 [DAT](INFO): entering command mode 2025-06-05T21:06:01.564Z,1749157561.564 [DAT](INFO): DAT read: 2025-06-05T21:06:01.565Z,1749157561.565 [DAT](INFO): DAT read: user:1> 2025-06-05T21:06:01.565Z,1749157561.565 [DAT](INFO): setting verbose to 3 2025-06-05T21:06:01.817Z,1749157561.817 [DAT](INFO): DAT read: user:1> 2025-06-05T21:06:01.818Z,1749157561.818 [DAT](INFO): DAT read: Verbose | 3 2025-06-05T21:06:01.818Z,1749157561.818 [DAT](INFO): set verbose to 3 2025-06-05T21:06:01.818Z,1749157561.818 [DAT](INFO): setting DatVerbose to 27440 2025-06-05T21:06:02.068Z,1749157562.068 [DAT](INFO): DAT read: user:2> 2025-06-05T21:06:02.069Z,1749157562.069 [DAT](INFO): DAT read: DatVerbose | 27440 2025-06-05T21:06:02.070Z,1749157562.070 [DAT](INFO): set DatVerbose to 27440 2025-06-05T21:06:02.070Z,1749157562.070 [DAT](INFO): setting transmit power to 8 2025-06-05T21:06:02.322Z,1749157562.322 [DAT](INFO): DAT read: user:3> 2025-06-05T21:06:02.323Z,1749157562.323 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-06-05T21:06:02.323Z,1749157562.323 [DAT](INFO): set transmit power to 8 2025-06-05T21:06:02.324Z,1749157562.324 [DAT](INFO): setting local address to 10 2025-06-05T21:06:02.573Z,1749157562.573 [DAT](INFO): DAT read: user:4> 2025-06-05T21:06:02.573Z,1749157562.573 [DAT](INFO): DAT read: LocalAddr | 10 2025-06-05T21:06:02.574Z,1749157562.574 [DAT](INFO): set local address to 10 2025-06-05T21:06:02.575Z,1749157562.575 [DAT](INFO): Setting time to: 21:6:2 And date to:6/5/2025 2025-06-05T21:06:02.825Z,1749157562.825 [DAT](INFO): DAT read: user:5> 2025-06-05T21:06:02.825Z,1749157562.825 [DAT](INFO): DAT read: Thu Jun 5, 2025 21:06:02 2025-06-05T21:06:02.826Z,1749157562.826 [DAT](INFO): Local DAT time set to Thu Jun 5, 2025 21:06:02 2025-06-05T21:06:08.121Z,1749157568.121 [NAL9602](INFO): Powering up NAL9602 2025-06-05T21:06:19.033Z,1749157579.033 [NAL9602](INFO): NAL9602 initialized 2025-06-05T21:06:43.675Z,1749157603.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=48144, MT Status=0, MTMSN=0 2025-06-05T21:06:43.675Z,1749157603.675 [NAL9602](INFO): No messages in MT queue 2025-06-05T21:06:50.951Z,1749157610.951 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210650.00,A,3654.38197,N,12206.58750,W,1.011,0.00,050625,,,A*71 2025-06-05T21:06:50.954Z,1749157610.954 [NAL9602](INFO): GPS fix at 20250605T210650: (36.906366, -122.109792) 2025-06-05T21:07:15.619Z,1749157635.619 [SBIT](IMPORTANT): Beginning Startup BIT 2025-06-05T21:07:15.624Z,1749157635.624 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-05T21:07:20.174Z,1749157640.174 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.084649 CHAN A1 (24V): 0.105488 CHAN A2 (12V): 0.047182 CHAN A3 (5V): 0.009055 CHAN B0 (3.3V): 0.006312 CHAN B1 (3.15aV): 0.005198 CHAN B2 (3.15bV): 0.015656 CHAN B3 (GND): 0.000140 OPEN: 0.002546 Full Scale: +/- 1 mA 2025-06-05T21:07:23.402Z,1749157643.402 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-05T21:08:09.329Z,1749157689.329 [SBIT](IMPORTANT): SBIT PASSED 2025-06-05T21:08:09.329Z,1749157689.329 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-06-05T21:08:09.330Z,1749157689.330 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere; 2025-06-05T21:08:09.330Z,1749157689.330 [SBIT](IMPORTANT): DockingStepper.closedValue=16450 count; 2025-06-05T21:08:09.330Z,1749157689.330 [SBIT](IMPORTANT): DockingStepper.slideValue=10700 count; 2025-06-05T21:08:09.330Z,1749157689.330 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2025-06-05T21:08:09.331Z,1749157689.331 [SBIT](IMPORTANT): RudderServo.offsetAngle=0.2 degree; 2025-06-05T21:08:09.331Z,1749157689.331 [SBIT](IMPORTANT): SpeedControl.rollOptimum=1.125000 m/s; 2025-06-05T21:08:09.331Z,1749157689.331 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=402.547437 cubic_centimeter; 2025-06-05T21:08:09.331Z,1749157689.331 [SBIT](IMPORTANT): VerticalControl.massDefault=19.020168 millimeter; 2025-06-05T21:08:09.682Z,1749157689.682 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-06-05T21:08:09.682Z,1749157689.682 [DefaultWithUndock] Running Loop=1 2025-06-05T21:08:09.682Z,1749157689.682 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-06-05T21:08:09.683Z,1749157689.683 [DefaultWithUndock:A.Wait] Running Loop=1 2025-06-05T21:08:09.683Z,1749157689.683 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:08:26.627Z,1749157706.627 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-06-05T21:08:39.985Z,1749157719.985 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-06-05T21:08:39.986Z,1749157719.986 [DefaultWithUndock:A.Wait] Stopped 2025-06-05T21:08:39.986Z,1749157719.986 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-05T21:08:39.986Z,1749157719.986 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-06-05T21:08:39.987Z,1749157719.987 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-06-05T21:08:39.987Z,1749157719.987 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-06-05T21:08:39.988Z,1749157719.988 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-06-05T21:08:39.989Z,1749157719.989 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-06-05T21:08:39.989Z,1749157719.989 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-06-05T21:08:39.990Z,1749157719.990 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-05T21:08:40.402Z,1749157720.402 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-06-05T21:08:40.402Z,1749157720.402 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-05T21:08:40.404Z,1749157720.404 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.034001 m. 2025-06-05T21:08:40.404Z,1749157720.404 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-06-05T21:08:40.405Z,1749157720.405 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-06-05T21:08:40.409Z,1749157720.409 [DockingStepper](INFO): Resume 2025-06-05T21:08:40.796Z,1749157720.796 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-06-05T21:08:40.802Z,1749157720.802 [DockingStepper](INFO): Resuming 2025-06-05T21:08:41.190Z,1749157721.190 [DockingStepper](INFO): Start 2025-06-05T21:08:41.190Z,1749157721.190 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-06-05T21:08:41.305Z,1749157721.305 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-06-05T21:08:41.595Z,1749157721.595 [DockingStepper](INFO): Starting 2025-06-05T21:08:42.069Z,1749157722.069 [DockingStepper](INFO): Starting 2025-06-05T21:08:42.561Z,1749157722.561 [DockingStepper](INFO): Starting 2025-06-05T21:08:42.612Z,1749157722.612 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2025-06-05T21:08:42.849Z,1749157722.849 [DockingStepper](INFO): Starting 2025-06-05T21:08:43.245Z,1749157723.245 [DockingStepper](INFO): Starting 2025-06-05T21:08:43.657Z,1749157723.657 [DockingStepper](INFO): Starting 2025-06-05T21:08:44.129Z,1749157724.129 [DockingStepper](INFO): Starting 2025-06-05T21:08:44.153Z,1749157724.153 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-06-05T21:08:44.465Z,1749157724.465 [DockingStepper](INFO): Starting 2025-06-05T21:08:51.936Z,1749157731.936 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:09:00.999Z,1749157740.999 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2025-06-05T21:09:01.406Z,1749157741.406 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:LeaveDock] Stopped 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-06-05T21:09:01.795Z,1749157741.795 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-06-05T21:09:01.796Z,1749157741.796 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-05T21:09:01.797Z,1749157741.797 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-05T21:09:01.797Z,1749157741.797 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-05T21:09:01.797Z,1749157741.797 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-05T21:09:01.798Z,1749157741.798 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-05T21:09:01.798Z,1749157741.798 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-05T21:09:01.798Z,1749157741.798 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-05T21:09:02.236Z,1749157742.236 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-06-05T21:09:02.236Z,1749157742.236 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:09:02.236Z,1749157742.236 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-06-05T21:09:02.381Z,1749157742.381 [DockingStepper](INFO): Pause 2025-06-05T21:09:02.608Z,1749157742.608 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-05T21:09:04.205Z,1749157744.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210903.00,A,3654.38474,N,12206.54349,W,1.458,74.42,050625,,,A*4C 2025-06-05T21:09:04.207Z,1749157744.207 [NAL9602](INFO): GPS fix at 20250605T210903: (36.906412, -122.109058) 2025-06-05T21:09:04.237Z,1749157744.237 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-06-05T21:09:04.237Z,1749157744.237 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-06-05T21:09:04.641Z,1749157744.641 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-05T21:09:23.080Z,1749157763.080 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:09:28.314Z,1749157768.314 [NAL9602](INFO): SBD MO Status=1, MOMSN=48145, MT Status=0, MTMSN=0 2025-06-05T21:09:28.368Z,1749157768.368 [NAL9602](INFO): Sent 18 bytes from file Logs/20250605T205824/Courier0004.lzma 2025-06-05T21:09:28.368Z,1749157768.368 [NAL9602](INFO): Packets left to send: 0 2025-06-05T21:09:31.258Z,1749157771.258 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004172 2025-06-05T21:09:38.872Z,1749157778.872 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250605T210525/Courier0000.lzma 2025-06-05T21:09:39.111Z,1749157779.111 [NAL9602](INFO): SBD MO Status=1, MOMSN=48146, MT Status=0, MTMSN=0 2025-06-05T21:09:39.164Z,1749157779.164 [NAL9602](INFO): Sent 268 bytes from file Logs/20250605T210525/Courier0000.lzma 2025-06-05T21:09:39.164Z,1749157779.164 [NAL9602](INFO): Packets left to send: 0 2025-06-05T21:09:39.208Z,1749157779.208 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-06-05T21:09:39.208Z,1749157779.208 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-06-05T21:09:39.208Z,1749157779.208 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:09:39.874Z,1749157779.874 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Courier0000.lzma.bak 2025-06-05T21:09:39.874Z,1749157779.874 [DataOverHttps](INFO): SBD MOMSN=25109282 2025-06-05T21:09:49.616Z,1749157789.616 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:09:58.452Z,1749157798.452 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:10:08.088Z,1749157808.088 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:10:09.815Z,1749157809.815 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-05T21:10:20.944Z,1749157820.944 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:10:30.812Z,1749157830.812 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:10:48.728Z,1749157848.728 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:11:01.404Z,1749157861.404 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:11:10.971Z,1749157870.971 [DataOverHttps](IMPORTANT): SBD MTMSN=20250605T211110 2025-06-05T21:11:21.780Z,1749157881.780 [DataOverHttps](INFO): Received command: sched asap "load Maintenance/optimize_roll_speed.tl;set optimize_roll_speed.Latitude 36.90378 degree;set optimize_roll_speed.Longitude -122.11055 degree;set optimize_roll_speed.StartSpeed 1 m/s;set optimize_roll_speed.InitStep 0.025 m/s" 2b46f 1 2 2025-06-05T21:11:21.789Z,1749157881.789 [CommandExec](IMPORTANT): got command schedule asap "load Maintenance/optimize_roll_speed.tl;set optimize_roll_speed.Latitude 36.90378 degree;set optimize_roll_speed.Longitude -122.11055 degree;set optimize_roll_speed.StartSpeed 1 m/s;set optimize_roll_speed.InitStep 0.025 m/s" 2b46f 1 2.000000 2025-06-05T21:11:21.789Z,1749157881.789 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=2b46f 2025-06-05T21:11:21.790Z,1749157881.790 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='2b46f'): "load Maintenance/optimize_roll_speed.tl;set optimize_roll_speed.Latitude 36.90378 degree;set optimize_roll_speed.Longitude -122.11055 degree;set optimize_roll_speed.StartSpeed 1 m/s;set optimize_roll_speed.InitStep 0.025 m/s", ASAP 2025-06-05T21:11:22.781Z,1749157882.781 [DataOverHttps](IMPORTANT): SBD MTMSN=20250605T211122 2025-06-05T21:11:32.656Z,1749157892.656 [DataOverHttps](INFO): Received command: sched asap "set optimize_roll_speed.MinStep 0.01 m/s;set optimize_roll_speed.Verbose 0 bool;run" 2b46f 2 2 2025-06-05T21:11:32.660Z,1749157892.660 [CommandExec](IMPORTANT): got command schedule asap "set optimize_roll_speed.MinStep 0.01 m/s;set optimize_roll_speed.Verbose 0 bool;run" 2b46f 2 2.000000 2025-06-05T21:11:32.660Z,1749157892.660 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=2b46f 2025-06-05T21:11:32.661Z,1749157892.661 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='2b46f'): "set optimize_roll_speed.MinStep 0.01 m/s;set optimize_roll_speed.Verbose 0 bool;run", ASAP 2025-06-05T21:11:32.803Z,1749157892.803 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/optimize_roll_speed.tl 2025-06-05T21:11:32.803Z,1749157892.803 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/optimize_roll_speed.tl 2025-06-05T21:11:32.803Z,1749157892.803 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/optimize_roll_speed.tl 2025-06-05T21:11:32.820Z,1749157892.820 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/optimize_roll_speed.tl 2025-06-05T21:11:33.606Z,1749157893.606 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/optimize_roll_speed.tl translated into: 60 30 NaN NaN 30 0.9 0 2 1.1 0.6 5 0.05 0.025 35 1 7 1 2025-06-05T21:11:33.613Z,1749157893.613 [MissionManager](INFO): DefineArg optimize_roll_speed.MissionTimeout = 60.000000 min 2025-06-05T21:11:33.615Z,1749157893.615 [MissionManager](INFO): DefineArg optimize_roll_speed.NeedCommsTime = 30.000000 min 2025-06-05T21:11:33.619Z,1749157893.619 [MissionManager](INFO): DefineArg optimize_roll_speed.Latitude = nan arcdeg 2025-06-05T21:11:33.622Z,1749157893.622 [MissionManager](INFO): DefineArg optimize_roll_speed.Longitude = nan arcdeg 2025-06-05T21:11:33.625Z,1749157893.625 [MissionManager](INFO): DefineArg optimize_roll_speed.Depth = 30.000000 m 2025-06-05T21:11:33.628Z,1749157893.628 [MissionManager](INFO): DefineArg optimize_roll_speed.StartSpeed = 0.900000 m/s 2025-06-05T21:11:33.631Z,1749157893.631 [MissionManager](INFO): DefineArg optimize_roll_speed.TargetRollAngle = 0.000000 arcdeg 2025-06-05T21:11:33.634Z,1749157893.634 [MissionManager](INFO): DefineArg optimize_roll_speed.RollError = 2.000000 arcdeg 2025-06-05T21:11:33.661Z,1749157893.661 [MissionManager](INFO): DefineArg optimize_roll_speed.MaxSpeed = 1.100000 m/s 2025-06-05T21:11:33.667Z,1749157893.667 [MissionManager](INFO): DefineArg optimize_roll_speed.MinSpeed = 0.600000 m/s 2025-06-05T21:11:33.688Z,1749157893.688 [MissionManager](INFO): DefineArg optimize_roll_speed.WindowLen = 5.000000 min 2025-06-05T21:11:33.691Z,1749157893.691 [MissionManager](INFO): DefineArg optimize_roll_speed.InitStep = 0.050000 m/s 2025-06-05T21:11:33.698Z,1749157893.698 [MissionManager](INFO): DefineArg optimize_roll_speed.MinStep = 0.025000 m/s 2025-06-05T21:11:33.717Z,1749157893.717 [MissionManager](INFO): DefineArg optimize_roll_speed.Verbose = 1 bool 2025-06-05T21:11:33.724Z,1749157893.724 [MissionManager](INFO): DefineArg optimize_roll_speed.MaxDepth = 35.000000 m 2025-06-05T21:11:33.727Z,1749157893.727 [MissionManager](INFO): DefineArg optimize_roll_speed.MinOffshore = 1.000000 km 2025-06-05T21:11:33.739Z,1749157893.739 [MissionManager](INFO): DefineArg optimize_roll_speed.MinAltitude = 7.000000 m 2025-06-05T21:11:33.742Z,1749157893.742 [MissionManager](INFO): DefineArg optimize_roll_speed.MassHold = 1 bool 2025-06-05T21:11:33.746Z,1749157893.746 [MissionManager](INFO): DefineArg optimize_roll_speed.BuoyancyHold = 1 bool 2025-06-05T21:11:33.746Z,1749157893.746 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-06-05T21:11:33.747Z,1749157893.747 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-06-05T21:11:33.757Z,1749157893.757 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-06-05T21:11:34.185Z,1749157894.185 [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-06-05T21:11:34.191Z,1749157894.191 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.DiveInterval = 1.000000 h 2025-06-05T21:11:34.194Z,1749157894.194 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.WaitForPitchUp = 10.000000 min 2025-06-05T21:11:34.198Z,1749157894.198 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-06-05T21:11:34.201Z,1749157894.201 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.SurfaceDepthRate = nan m/s 2025-06-05T21:11:34.225Z,1749157894.225 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-06-05T21:11:34.232Z,1749157894.232 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.SurfacingTimeout = 1000.000000 s 2025-06-05T21:11:34.235Z,1749157894.235 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.GPSTimeout = 7.000000 min 2025-06-05T21:11:34.243Z,1749157894.243 [MissionManager](INFO): DefineArg optimize_roll_speed:NeedComms.CommsTimeout = 30.000000 min 2025-06-05T21:11:34.259Z,1749157894.259 [optimize_roll_speed:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-05T21:11:34.338Z,1749157894.338 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-06-05T21:11:34.338Z,1749157894.338 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-06-05T21:11:34.339Z,1749157894.339 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-06-05T21:11:34.549Z,1749157894.549 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-06-05T21:11:34.559Z,1749157894.559 [MissionManager](INFO): DefineArg optimize_roll_speed:StandardEnvelopes.MinAltitude = 5.000000 m 2025-06-05T21:11:34.570Z,1749157894.570 [MissionManager](INFO): DefineArg optimize_roll_speed:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-06-05T21:11:34.574Z,1749157894.574 [MissionManager](INFO): DefineArg optimize_roll_speed:StandardEnvelopes.MaxDepth = 200.000000 m 2025-06-05T21:11:34.585Z,1749157894.585 [MissionManager](INFO): DefineArg optimize_roll_speed:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-06-05T21:11:34.586Z,1749157894.586 [optimize_roll_speed:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-06-05T21:11:34.606Z,1749157894.606 [optimize_roll_speed:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-06-05T21:11:34.632Z,1749157894.632 [optimize_roll_speed:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-06-05T21:11:34.656Z,1749157894.656 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-06-05T21:11:34.656Z,1749157894.656 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-06-05T21:11:34.657Z,1749157894.657 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-06-05T21:11:34.660Z,1749157894.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:11:34.697Z,1749157894.697 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-06-05T21:11:34.735Z,1749157894.735 [MissionManager](INFO): DefineArg optimize_roll_speed:BackseatDriver.EnableBackseat = 0 bool 2025-06-05T21:11:34.754Z,1749157894.754 [optimize_roll_speed:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-06-05T21:11:34.762Z,1749157894.762 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-06-05T21:11:34.762Z,1749157894.762 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-06-05T21:11:34.763Z,1749157894.763 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-06-05T21:11:35.062Z,1749157895.062 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-06-05T21:11:35.075Z,1749157895.075 [MissionManager](INFO): DefineArg optimize_roll_speed:PowerOnly.SampleAll = 0 bool 2025-06-05T21:11:35.082Z,1749157895.082 [MissionManager](INFO): DefineArg optimize_roll_speed:PowerOnly.SampleLoad1 = 0 bool 2025-06-05T21:11:35.096Z,1749157895.096 [MissionManager](INFO): DefineArg optimize_roll_speed:PowerOnly.SampleLoad2 = 0 bool 2025-06-05T21:11:35.099Z,1749157895.099 [MissionManager](INFO): DefineArg optimize_roll_speed:PowerOnly.SampleLoad3 = 0 bool 2025-06-05T21:11:35.135Z,1749157895.135 [MissionManager](INFO): DefineArg optimize_roll_speed:PowerOnly.EnabledPowerOnly = 0 bool 2025-06-05T21:11:35.154Z,1749157895.154 [optimize_roll_speed:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-06-05T21:11:35.169Z,1749157895.169 [optimize_roll_speed:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-05T21:11:35.181Z,1749157895.181 [optimize_roll_speed:MassHold.Pitch](DEBUG): Construct. 2025-06-05T21:11:35.199Z,1749157895.199 [optimize_roll_speed:Transit:A.Pitch](DEBUG): Construct. 2025-06-05T21:11:35.218Z,1749157895.218 [optimize_roll_speed:Transit:B.SetRollSpeed](DEBUG): Construct. 2025-06-05T21:11:35.245Z,1749157895.245 [optimize_roll_speed:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-06-05T21:11:35.285Z,1749157895.285 [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 optimize_roll_speed { """ Vehicle transits to desired waypoint while optimizing speed to achieve a target roll angle. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Depth = 30 meter """ Depth of flat and level flight during the mission. """ StartSpeed = 0.9 meter_per_second """ Initial speed for roll optimization. """ TargetRollAngle = 0 degree """ Target roll angle for roll speed optimization. """ RollError = 2 degree """ Error tolerance for roll angle during speed optimization. """ MaxSpeed = 1.1 meter_per_second """ Maximum speed for roll speed optimization. """ MinSpeed = 0.6 meter_per_second """ Minimum speed for roll speed optimization. """ WindowLen = 5 minute """ Time window for roll speed optimization. """ InitStep = 0.05 meter_per_second """ Initial step size for roll speed optimization. """ MinStep = 0.025 meter_per_second """ Minimum step size for roll speed optimization. """ Verbose = true """ Enable verbose logging for roll speed optimization. """ MaxDepth = 35 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl call id="StartingMission" priorityHere=false refId="NeedComms" behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetRollSpeed { run in parallel set targetRoll = TargetRollAngle set rollError = RollError set maxSpeed = MaxSpeed set startSpeed = StartSpeed set minSpeed = MinSpeed set estimationWindow = WindowLen set initStep = InitStep set minStep = MinStep set verbose = Verbose set persistResult = true } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } call id="PhoneHome" priorityHere=false refId="NeedComms" } } 2025-06-05T21:11:35.285Z,1749157895.285 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/optimize_roll_speed.tl id=optimize_roll_speed 2025-06-05T21:11:48.072Z,1749157908.072 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:11:58.331Z,1749157918.331 [CommandExec](IMPORTANT): got command set optimize_roll_speed.Latitude 36.90378 degree 2025-06-05T21:11:58.340Z,1749157918.340 [CommandExec](IMPORTANT): got command set optimize_roll_speed.Longitude -122.11055 degree 2025-06-05T21:11:58.341Z,1749157918.341 [CommandExec](IMPORTANT): got command set optimize_roll_speed.StartSpeed 1 meter_per_second 2025-06-05T21:11:58.341Z,1749157918.341 [CommandExec](IMPORTANT): got command set optimize_roll_speed.InitStep 0.025 meter_per_second 2025-06-05T21:12:04.896Z,1749157924.896 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:12:15.359Z,1749157935.359 [CommandExec](IMPORTANT): got command set optimize_roll_speed.MinStep 0.01 meter_per_second 2025-06-05T21:12:15.367Z,1749157935.367 [CommandExec](IMPORTANT): got command set optimize_roll_speed.Verbose 0 bool 2025-06-05T21:12:15.367Z,1749157935.367 [CommandExec](IMPORTANT): got command run 2025-06-05T21:12:15.382Z,1749157935.382 [CommandExec](IMPORTANT): Running 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock] Stopped 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault] Stopped 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-06-05T21:12:15.700Z,1749157935.700 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-05T21:12:15.701Z,1749157935.701 [MissionManager](IMPORTANT): Started mission optimize_roll_speed 2025-06-05T21:12:15.701Z,1749157935.701 [optimize_roll_speed] Running Loop=1 2025-06-05T21:12:15.701Z,1749157935.701 [optimize_roll_speed](DEBUG): Aggregate::initialize optimize_roll_speed 2025-06-05T21:12:15.701Z,1749157935.701 [optimize_roll_speed:StandardEnvelopes] Running Loop=1 2025-06-05T21:12:15.701Z,1749157935.701 [optimize_roll_speed:StandardEnvelopes](DEBUG): Aggregate::initialize optimize_roll_speed:StandardEnvelopes 2025-06-05T21:12:15.701Z,1749157935.701 [optimize_roll_speed:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-05T21:12:15.701Z,1749157935.701 [optimize_roll_speed:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:BackseatDriver] Running Loop=1 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:BackseatDriver](DEBUG): Aggregate::initialize optimize_roll_speed:BackseatDriver 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-06-05T21:12:15.702Z,1749157935.702 [optimize_roll_speed:PowerOnly] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:PowerOnly](DEBUG): Aggregate::initialize optimize_roll_speed:PowerOnly 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:PowerOnly:A] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:PowerOnly:B] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:PowerOnly:C] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:PowerOnly:D] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:PowerOnly:E.Wait] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:MassHold.Pitch] Running Loop=1 2025-06-05T21:12:15.703Z,1749157935.703 [optimize_roll_speed:MassHold.Pitch](DEBUG): Initialize. 2025-06-05T21:12:15.740Z,1749157935.740 [optimize_roll_speed:B] Running Loop=1 2025-06-05T21:12:15.741Z,1749157935.741 [optimize_roll_speed:MassHold.Pitch] Running Loop=1 2025-06-05T21:12:15.741Z,1749157935.741 [optimize_roll_speed:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-05T21:12:15.741Z,1749157935.741 [optimize_roll_speed:PowerOnly] Running Loop=1 2025-06-05T21:12:15.742Z,1749157935.742 [optimize_roll_speed:PowerOnly:D] Stopped 2025-06-05T21:12:15.742Z,1749157935.742 [optimize_roll_speed:PowerOnly:C] Stopped 2025-06-05T21:12:15.742Z,1749157935.742 [optimize_roll_speed:PowerOnly:B] Stopped 2025-06-05T21:12:15.742Z,1749157935.742 [optimize_roll_speed:PowerOnly:A] Stopped 2025-06-05T21:12:15.742Z,1749157935.742 [optimize_roll_speed:BackseatDriver] Running Loop=1 2025-06-05T21:12:15.742Z,1749157935.742 [optimize_roll_speed:StandardEnvelopes] Running Loop=1 2025-06-05T21:12:15.742Z,1749157935.742 [optimize_roll_speed:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-05T21:12:15.743Z,1749157935.743 [optimize_roll_speed:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-05T21:12:15.760Z,1749157935.760 [optimize_roll_speed:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-05T21:12:15.761Z,1749157935.761 [optimize_roll_speed:B] Stopped 2025-06-05T21:12:15.761Z,1749157935.761 [optimize_roll_speed:C] Running Loop=1 2025-06-05T21:12:16.225Z,1749157936.225 [optimize_roll_speed:C] Stopped 2025-06-05T21:12:16.225Z,1749157936.225 [optimize_roll_speed:E] Running Loop=1 2025-06-05T21:12:16.533Z,1749157936.533 [optimize_roll_speed:E] Stopped 2025-06-05T21:12:16.534Z,1749157936.534 [optimize_roll_speed:F] Running Loop=1 2025-06-05T21:12:16.926Z,1749157936.926 [optimize_roll_speed:F] Stopped 2025-06-05T21:12:16.926Z,1749157936.926 [optimize_roll_speed:G] Running Loop=1 2025-06-05T21:12:17.352Z,1749157937.352 [optimize_roll_speed:G] Stopped 2025-06-05T21:12:17.352Z,1749157937.352 [optimize_roll_speed:StartingMission] Running Loop=1 2025-06-05T21:12:17.352Z,1749157937.352 [optimize_roll_speed:StartingMission](DEBUG): Aggregate::initialize optimize_roll_speed:StartingMission 2025-06-05T21:12:17.780Z,1749157937.780 [optimize_roll_speed:NeedComms] Running Loop=1 2025-06-05T21:12:17.780Z,1749157937.780 [optimize_roll_speed:NeedComms](DEBUG): Aggregate::initialize optimize_roll_speed:NeedComms 2025-06-05T21:12:17.780Z,1749157937.780 [optimize_roll_speed:NeedComms:B.GoToSurface] Running Loop=1 2025-06-05T21:12:17.780Z,1749157937.780 [optimize_roll_speed:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-05T21:12:17.781Z,1749157937.781 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-05T21:12:17.781Z,1749157937.781 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-05T21:12:17.781Z,1749157937.781 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-05T21:12:17.782Z,1749157937.782 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-05T21:12:17.782Z,1749157937.782 [optimize_roll_speed:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-05T21:12:17.782Z,1749157937.782 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-05T21:12:17.783Z,1749157937.783 [optimize_roll_speed:NeedComms:A] Running Loop=1 2025-06-05T21:12:17.796Z,1749157937.796 [optimize_roll_speed:NeedComms:A](INFO): last time_fix was: 1749157743.000000 second since 1970/01/01T00:00:00Z 2025-06-05T21:12:17.796Z,1749157937.796 [optimize_roll_speed:NeedComms:A] Stopped 2025-06-05T21:12:18.162Z,1749157938.162 [optimize_roll_speed:NeedComms:C] Running Loop=1 2025-06-05T21:12:18.523Z,1749157938.523 [optimize_roll_speed:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-05T21:12:20.103Z,1749157940.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211219.00,A,3654.38497,N,12206.49663,W,1.497,96.26,050625,,,A*4C 2025-06-05T21:12:20.109Z,1749157940.109 [NAL9602](INFO): GPS fix at 20250605T211219: (36.906416, -122.108277) 2025-06-05T21:12:20.225Z,1749157940.225 [optimize_roll_speed:NeedComms:C] Stopped 2025-06-05T21:12:20.226Z,1749157940.226 [optimize_roll_speed:NeedComms:D] Running Loop=1 2025-06-05T21:12:20.552Z,1749157940.552 [optimize_roll_speed:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-05T21:12:46.915Z,1749157966.915 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250605T210525/Courier0004.lzma 2025-06-05T21:12:49.926Z,1749157969.926 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Courier0004.lzma.bak 2025-06-05T21:12:49.926Z,1749157969.926 [DataOverHttps](INFO): SBD MOMSN=25109293 2025-06-05T21:13:21.179Z,1749158001.179 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250605T205824/Express0005.lzma 2025-06-05T21:13:22.182Z,1749158002.182 [DataOverHttps](INFO): Moved sent file to Logs/20250605T205824/Express0005.lzma.bak 2025-06-05T21:13:22.182Z,1749158002.182 [DataOverHttps](INFO): SBD MOMSN=25109296 2025-06-05T21:13:45.850Z,1749158025.850 [DataOverHttps](INFO): Sending 1180 bytes from file Logs/20250605T210525/Express0001.lzma 2025-06-05T21:13:50.866Z,1749158030.866 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0001.lzma.bak 2025-06-05T21:13:50.866Z,1749158030.866 [DataOverHttps](INFO): SBD MOMSN=25109298 2025-06-05T21:14:11.497Z,1749158051.497 [DataOverHttps](INFO): Sending 606 bytes from file Logs/20250605T210525/Express0005.lzma 2025-06-05T21:14:12.502Z,1749158052.502 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0005.lzma.bak 2025-06-05T21:14:12.502Z,1749158052.502 [DataOverHttps](INFO): SBD MOMSN=25109323 2025-06-05T21:14:30.588Z,1749158070.588 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250605T210525/Express0008.lzma 2025-06-05T21:14:31.590Z,1749158071.590 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0008.lzma.bak 2025-06-05T21:14:31.590Z,1749158071.590 [DataOverHttps](INFO): SBD MOMSN=25109344 2025-06-05T21:14:33.139Z,1749158073.139 [optimize_roll_speed:NeedComms:D] Stopped 2025-06-05T21:14:33.139Z,1749158073.139 [optimize_roll_speed:NeedComms:E] Running Loop=1 2025-06-05T21:14:33.537Z,1749158073.537 [optimize_roll_speed:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-05T21:14:33.935Z,1749158073.935 [NAL9602](INFO): SBD MO Status=2, MOMSN=48147, MT Status=2, MTMSN=0 2025-06-05T21:14:33.936Z,1749158073.936 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:14:41.552Z,1749158081.552 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:14:50.516Z,1749158090.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:14:59.396Z,1749158099.396 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:15:08.284Z,1749158108.284 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:15:12.310Z,1749158112.310 [NAL9602](INFO): SBD MO Status=2, MOMSN=48147, MT Status=2, MTMSN=0 2025-06-05T21:15:12.310Z,1749158112.310 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:15:17.186Z,1749158117.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:15:22.410Z,1749158122.410 [NAL9602](INFO): SBD MO Status=0, MOMSN=48147, MT Status=0, MTMSN=0 2025-06-05T21:15:22.410Z,1749158122.410 [NAL9602](INFO): No messages in MT queue 2025-06-05T21:15:23.626Z,1749158123.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211524.00,A,3654.38258,N,12206.45082,W,1.127,84.22,050625,,,D*49 2025-06-05T21:15:23.629Z,1749158123.629 [NAL9602](INFO): GPS fix at 20250605T211524: (36.906376, -122.107514) 2025-06-05T21:15:23.666Z,1749158123.666 [optimize_roll_speed:NeedComms:E] Stopped 2025-06-05T21:15:23.672Z,1749158123.672 [optimize_roll_speed:NeedComms](INFO): Completed optimize_roll_speed:NeedComms 2025-06-05T21:15:23.672Z,1749158123.672 [optimize_roll_speed:NeedComms] Stopped 2025-06-05T21:15:23.673Z,1749158123.673 [optimize_roll_speed:NeedComms](DEBUG): Aggregate::uninitialize optimize_roll_speed:NeedComms 2025-06-05T21:15:23.673Z,1749158123.673 [optimize_roll_speed:NeedComms:B.GoToSurface] Stopped 2025-06-05T21:15:23.673Z,1749158123.673 [optimize_roll_speed:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-05T21:15:24.122Z,1749158124.122 [optimize_roll_speed:StartingMission](INFO): Completed optimize_roll_speed:StartingMission 2025-06-05T21:15:24.122Z,1749158124.122 [optimize_roll_speed:StartingMission] Stopped 2025-06-05T21:15:24.122Z,1749158124.122 [optimize_roll_speed:StartingMission](DEBUG): Aggregate::uninitialize optimize_roll_speed:StartingMission 2025-06-05T21:15:24.122Z,1749158124.122 [optimize_roll_speed:Transit] Running Loop=1 2025-06-05T21:15:24.123Z,1749158124.123 [optimize_roll_speed:Transit](DEBUG): Aggregate::initialize optimize_roll_speed:Transit 2025-06-05T21:15:24.123Z,1749158124.123 [optimize_roll_speed:Transit:A.Pitch] Running Loop=1 2025-06-05T21:15:24.123Z,1749158124.123 [optimize_roll_speed:Transit:A.Pitch](DEBUG): Initialize. 2025-06-05T21:15:24.123Z,1749158124.123 [optimize_roll_speed:Transit:B.SetRollSpeed] Running Loop=1 2025-06-05T21:15:24.123Z,1749158124.123 [optimize_roll_speed:Transit:B.SetRollSpeed](DEBUG): Initialize. 2025-06-05T21:15:24.128Z,1749158124.128 [optimize_roll_speed:Transit:Wpt1.Waypoint] Running Loop=1 2025-06-05T21:15:24.128Z,1749158124.128 [optimize_roll_speed:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-06-05T21:15:24.130Z,1749158124.130 [optimize_roll_speed:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.903782,-122.110550 2025-06-05T21:15:24.457Z,1749158124.457 [optimize_roll_speed:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.903782,-122.110550 2025-06-05T21:15:24.458Z,1749158124.458 [optimize_roll_speed:Transit:B.SetRollSpeed] Running Loop=1 2025-06-05T21:15:24.458Z,1749158124.458 [optimize_roll_speed:Transit:A.Pitch] Running Loop=1 2025-06-05T21:15:24.467Z,1749158124.467 [HorizontalControl](DEBUG): kpHeading = 0.400000 2025-06-05T21:15:24.468Z,1749158124.468 [HorizontalControl](DEBUG): kiHeading = 0.001000 2025-06-05T21:15:24.468Z,1749158124.468 [HorizontalControl](DEBUG): kdHeading = 0.050000 2025-06-05T21:15:26.030Z,1749158126.030 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:15:35.644Z,1749158135.644 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:15:44.940Z,1749158144.940 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:15:56.342Z,1749158156.342 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-05T21:16:13.348Z,1749158173.348 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:16:44.620Z,1749158204.620 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:17:12.732Z,1749158232.732 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.387020 2025-06-05T21:17:13.736Z,1749158233.736 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:17:33.472Z,1749158253.472 [Radio_Surface](INFO): Powering down 2025-06-05T21:17:43.864Z,1749158263.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:17:44.868Z,1749158264.868 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-05T21:17:44.868Z,1749158264.868 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:18:04.489Z,1749158284.489 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-06-05T21:18:15.004Z,1749158295.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:18:16.010Z,1749158296.010 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:18:17.418Z,1749158297.418 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-06-05T21:18:46.148Z,1749158326.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:18:47.152Z,1749158327.152 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:19:17.280Z,1749158357.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:19:18.286Z,1749158358.286 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:19:48.404Z,1749158388.404 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:19:49.408Z,1749158389.408 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:20:19.540Z,1749158419.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:20:20.544Z,1749158420.544 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:20:24.316Z,1749158424.316 [optimize_roll_speed:Transit:B.SetRollSpeed](INFO): Adjusting speed to 1.025000 m/s (avg roll: 2.326768 degrees, target: 0.000000 degrees) 2025-06-05T21:20:50.664Z,1749158450.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:20:51.668Z,1749158451.668 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:21:21.788Z,1749158481.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:21:22.792Z,1749158482.792 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:21:30.135Z,1749158490.135 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 386.29, 388.17, 405.04 2025-06-05T21:21:52.944Z,1749158512.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:21:53.948Z,1749158513.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:22:24.068Z,1749158544.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:22:25.072Z,1749158545.072 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:22:55.196Z,1749158575.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:22:56.200Z,1749158576.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:23:26.320Z,1749158606.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:23:27.324Z,1749158607.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:23:52.387Z,1749158632.387 [optimize_roll_speed:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.903782,-122.110550 2025-06-05T21:23:52.387Z,1749158632.387 [optimize_roll_speed:Transit:Wpt1.Waypoint] Stopped 2025-06-05T21:23:52.387Z,1749158632.387 [optimize_roll_speed:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-06-05T21:23:52.392Z,1749158632.392 [optimize_roll_speed:Transit:PhoneHome] Running Loop=1 2025-06-05T21:23:52.392Z,1749158632.392 [optimize_roll_speed:Transit:PhoneHome](DEBUG): Aggregate::initialize optimize_roll_speed:Transit:PhoneHome 2025-06-05T21:23:52.773Z,1749158632.773 [optimize_roll_speed:NeedComms] Running Loop=1 2025-06-05T21:23:52.773Z,1749158632.773 [optimize_roll_speed:NeedComms](DEBUG): Aggregate::initialize optimize_roll_speed:NeedComms 2025-06-05T21:23:52.773Z,1749158632.773 [optimize_roll_speed:NeedComms:B.GoToSurface] Running Loop=1 2025-06-05T21:23:52.773Z,1749158632.773 [optimize_roll_speed:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-05T21:23:52.774Z,1749158632.774 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-05T21:23:52.774Z,1749158632.774 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-05T21:23:52.774Z,1749158632.774 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-05T21:23:52.775Z,1749158632.775 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-05T21:23:52.775Z,1749158632.775 [optimize_roll_speed:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-05T21:23:52.775Z,1749158632.775 [optimize_roll_speed:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-05T21:23:52.776Z,1749158632.776 [optimize_roll_speed:NeedComms:A] Running Loop=1 2025-06-05T21:23:52.778Z,1749158632.778 [optimize_roll_speed:NeedComms:A](INFO): last time_fix was: 1749158124.000000 second since 1970/01/01T00:00:00Z 2025-06-05T21:23:52.778Z,1749158632.778 [optimize_roll_speed:NeedComms:A] Stopped 2025-06-05T21:23:57.444Z,1749158637.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:23:58.448Z,1749158638.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:24:28.568Z,1749158668.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:24:29.572Z,1749158669.572 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:24:59.692Z,1749158699.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:25:00.697Z,1749158700.697 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:25:24.932Z,1749158724.932 [optimize_roll_speed:Transit:B.SetRollSpeed](INFO): Crossed target. New step: 0.012500 m/s 2025-06-05T21:25:24.933Z,1749158724.933 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): Optimum speed found: 1.025000 m/s for target roll 0.000000 degrees 2025-06-05T21:25:24.933Z,1749158724.933 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): Persisting optimum speed 1.025000 m/s for 0.000000 degrees roll to config 2025-06-05T21:25:24.937Z,1749158724.937 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-06-05T21:25:24.937Z,1749158724.937 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): CBIT.gf24Offset=173 microampere; 2025-06-05T21:25:24.938Z,1749158724.938 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): DockingStepper.closedValue=16450 count; 2025-06-05T21:25:24.939Z,1749158724.939 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): DockingStepper.slideValue=10700 count; 2025-06-05T21:25:24.939Z,1749158724.939 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2025-06-05T21:25:24.940Z,1749158724.940 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): RudderServo.offsetAngle=0.2 degree; 2025-06-05T21:25:24.941Z,1749158724.941 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): SpeedControl.rollOptimum=1.025000 m/s; 2025-06-05T21:25:24.942Z,1749158724.942 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): VerticalControl.buoyancyNeutral=402.547437 cubic_centimeter; 2025-06-05T21:25:24.943Z,1749158724.943 [optimize_roll_speed:Transit:B.SetRollSpeed](IMPORTANT): VerticalControl.massDefault=19.020168 millimeter; 2025-06-05T21:25:30.828Z,1749158730.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:25:31.832Z,1749158731.832 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:25:43.123Z,1749158743.123 [optimize_roll_speed:NeedComms:C] Running Loop=1 2025-06-05T21:25:43.612Z,1749158743.612 [Radio_Surface](INFO): Powering up 2025-06-05T21:25:48.864Z,1749158748.864 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2025-06-05T21:25:48.865Z,1749158748.865 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0 2025-06-05T21:26:01.952Z,1749158761.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:26:02.956Z,1749158762.956 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-05T21:26:02.956Z,1749158762.956 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:26:14.622Z,1749158774.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=48148, MT Status=2, MTMSN=0 2025-06-05T21:26:14.623Z,1749158774.623 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:26:33.076Z,1749158793.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:26:33.594Z,1749158793.594 [NAL9602](INFO): SBD MO Status=0, MOMSN=48148, MT Status=0, MTMSN=0 2025-06-05T21:26:33.595Z,1749158793.595 [NAL9602](INFO): No messages in MT queue 2025-06-05T21:26:34.080Z,1749158794.080 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:26:34.809Z,1749158794.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212635.00,A,3654.18750,N,12206.64784,W,0.914,80.65,050625,,,D*4A 2025-06-05T21:26:34.811Z,1749158794.811 [NAL9602](INFO): GPS fix at 20250605T212635: (36.903125, -122.110797) 2025-06-05T21:26:34.825Z,1749158794.825 [UniversalFixResidualReporter](INFO): Fix residual: 8.2 %DT, over the last 553.6 m. Residual distance 45.2 m at bearing 113.4 degrees. Fix at (36.9031, -122.1108) with 464.7 m made good. 2025-06-05T21:26:34.828Z,1749158794.828 [optimize_roll_speed:NeedComms:C] Stopped 2025-06-05T21:26:34.829Z,1749158794.829 [optimize_roll_speed:NeedComms:D] Running Loop=1 2025-06-05T21:27:04.200Z,1749158824.200 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:27:05.204Z,1749158825.204 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:27:08.819Z,1749158828.819 [NAL9602](INFO): SBD MO Status=1, MOMSN=48149, MT Status=0, MTMSN=0 2025-06-05T21:27:08.880Z,1749158828.880 [NAL9602](INFO): Sent 206 bytes from file Logs/20250605T210525/Courier0010.lzma 2025-06-05T21:27:08.880Z,1749158828.880 [NAL9602](INFO): Packets left to send: 0 2025-06-05T21:27:27.139Z,1749158847.139 [NAL9602](INFO): SBD MO Status=2, MOMSN=48150, MT Status=2, MTMSN=0 2025-06-05T21:27:27.139Z,1749158847.139 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:27:35.324Z,1749158855.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:27:36.334Z,1749158856.334 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:27:45.585Z,1749158865.585 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-06-05T21:27:53.669Z,1749158873.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=48150, MT Status=2, MTMSN=0 2025-06-05T21:27:53.669Z,1749158873.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:28:06.464Z,1749158886.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:28:07.468Z,1749158887.468 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:28:18.050Z,1749158898.050 [NAL9602](INFO): SBD MO Status=2, MOMSN=48150, MT Status=2, MTMSN=0 2025-06-05T21:28:18.050Z,1749158898.050 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:28:35.120Z,1749158915.120 [NAL9602](INFO): SBD MO Status=2, MOMSN=48150, MT Status=2, MTMSN=0 2025-06-05T21:28:35.121Z,1749158915.121 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:28:37.588Z,1749158917.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:28:38.592Z,1749158918.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:29:08.712Z,1749158948.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-05T21:29:09.716Z,1749158949.716 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:29:26.640Z,1749158966.640 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003257 2025-06-05T21:29:35.385Z,1749158975.385 [DataOverHttps](INFO): Sending 681 bytes from file Logs/20250605T210525/Express0011.lzma 2025-06-05T21:29:41.418Z,1749158981.418 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0011.lzma.bak 2025-06-05T21:29:41.418Z,1749158981.418 [DataOverHttps](INFO): SBD MOMSN=25109385 2025-06-05T21:29:43.010Z,1749158983.010 [optimize_roll_speed:NeedComms:D] Stopped 2025-06-05T21:29:43.010Z,1749158983.010 [optimize_roll_speed:NeedComms:E] Running Loop=1 2025-06-05T21:29:45.014Z,1749158985.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212945.00,A,3654.18709,N,12206.59555,W,1.089,50.13,050625,,,A*4B 2025-06-05T21:29:45.017Z,1749158985.017 [NAL9602](INFO): GPS fix at 20250605T212945: (36.903118, -122.109926) 2025-06-05T21:29:45.046Z,1749158985.046 [optimize_roll_speed:NeedComms:E] Stopped 2025-06-05T21:29:45.047Z,1749158985.047 [optimize_roll_speed:NeedComms](INFO): Completed optimize_roll_speed:NeedComms 2025-06-05T21:29:45.047Z,1749158985.047 [optimize_roll_speed:NeedComms] Stopped 2025-06-05T21:29:45.076Z,1749158985.076 [optimize_roll_speed:NeedComms](DEBUG): Aggregate::uninitialize optimize_roll_speed:NeedComms 2025-06-05T21:29:45.076Z,1749158985.076 [optimize_roll_speed:NeedComms:B.GoToSurface] Stopped 2025-06-05T21:29:45.076Z,1749158985.076 [optimize_roll_speed:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-05T21:29:45.464Z,1749158985.464 [optimize_roll_speed:Transit:PhoneHome](INFO): Completed optimize_roll_speed:Transit:PhoneHome 2025-06-05T21:29:45.464Z,1749158985.464 [optimize_roll_speed:Transit:PhoneHome] Stopped 2025-06-05T21:29:45.464Z,1749158985.464 [optimize_roll_speed:Transit:PhoneHome](DEBUG): Aggregate::uninitialize optimize_roll_speed:Transit:PhoneHome 2025-06-05T21:29:45.465Z,1749158985.465 [optimize_roll_speed:Transit](INFO): Completed optimize_roll_speed:Transit 2025-06-05T21:29:45.465Z,1749158985.465 [optimize_roll_speed:Transit] Stopped 2025-06-05T21:29:45.465Z,1749158985.465 [optimize_roll_speed:Transit](DEBUG): Aggregate::uninitialize optimize_roll_speed:Transit 2025-06-05T21:29:45.466Z,1749158985.466 [optimize_roll_speed:Transit:A.Pitch] Stopped 2025-06-05T21:29:45.466Z,1749158985.466 [optimize_roll_speed:Transit:B.SetRollSpeed] Stopped 2025-06-05T21:29:45.466Z,1749158985.466 [optimize_roll_speed:Transit:B.SetRollSpeed](DEBUG): Uninitialize. 2025-06-05T21:29:45.488Z,1749158985.488 [optimize_roll_speed](INFO): Completed optimize_roll_speed 2025-06-05T21:29:45.488Z,1749158985.488 [MissionManager](INFO): optimize_roll_speed is completed. 2025-06-05T21:29:45.488Z,1749158985.488 [MissionManager](INFO): Uninitializing Mission optimize_roll_speed 2025-06-05T21:29:45.488Z,1749158985.488 [optimize_roll_speed] Stopped 2025-06-05T21:29:45.488Z,1749158985.488 [optimize_roll_speed](DEBUG): Aggregate::uninitialize optimize_roll_speed 2025-06-05T21:29:45.488Z,1749158985.488 [optimize_roll_speed:StandardEnvelopes] Stopped 2025-06-05T21:29:45.488Z,1749158985.488 [optimize_roll_speed:StandardEnvelopes](DEBUG): Aggregate::uninitialize optimize_roll_speed:StandardEnvelopes 2025-06-05T21:29:45.488Z,1749158985.488 [optimize_roll_speed:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:BackseatDriver] Stopped 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:BackseatDriver](DEBUG): Aggregate::uninitialize optimize_roll_speed:BackseatDriver 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:BackseatDriver:A.BackseatDriver] Stopped 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:PowerOnly] Stopped 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:PowerOnly](DEBUG): Aggregate::uninitialize optimize_roll_speed:PowerOnly 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:PowerOnly:E.Wait] Stopped 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-06-05T21:29:45.489Z,1749158985.489 [optimize_roll_speed:BuoyancyHold.Buoyancy] Stopped 2025-06-05T21:29:45.490Z,1749158985.490 [optimize_roll_speed:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-05T21:29:45.490Z,1749158985.490 [optimize_roll_speed:MassHold.Pitch] Stopped 2025-06-05T21:29:45.857Z,1749158985.857 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-06-05T21:29:45.857Z,1749158985.857 [DefaultWithUndock] Running Loop=1 2025-06-05T21:29:45.857Z,1749158985.857 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-06-05T21:29:45.857Z,1749158985.857 [DefaultWithUndock:A.Wait] Running Loop=1 2025-06-05T21:29:45.857Z,1749158985.857 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:29:51.564Z,1749158991.564 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:30:01.656Z,1749159001.656 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:30:15.933Z,1749159015.933 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-06-05T21:30:15.933Z,1749159015.933 [DefaultWithUndock:A.Wait] Stopped 2025-06-05T21:30:15.933Z,1749159015.933 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-05T21:30:15.933Z,1749159015.933 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-06-05T21:30:15.933Z,1749159015.933 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-06-05T21:30:15.933Z,1749159015.933 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-06-05T21:30:15.933Z,1749159015.933 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-06-05T21:30:15.934Z,1749159015.934 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-06-05T21:30:15.934Z,1749159015.934 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-06-05T21:30:15.934Z,1749159015.934 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-05T21:30:16.366Z,1749159016.366 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-06-05T21:30:16.366Z,1749159016.366 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-05T21:30:16.367Z,1749159016.367 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 0.020710 m. 2025-06-05T21:30:16.367Z,1749159016.367 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-06-05T21:30:16.367Z,1749159016.367 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-06-05T21:30:16.372Z,1749159016.372 [DockingStepper](INFO): Resume 2025-06-05T21:30:16.761Z,1749159016.761 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-06-05T21:30:16.768Z,1749159016.768 [DockingStepper](INFO): Resuming 2025-06-05T21:30:17.196Z,1749159017.196 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-05T21:30:17.245Z,1749159017.245 [DockingStepper](INFO): Start 2025-06-05T21:30:17.245Z,1749159017.245 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-06-05T21:30:17.365Z,1749159017.365 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-06-05T21:30:17.704Z,1749159017.704 [DockingStepper](INFO): Starting 2025-06-05T21:30:18.069Z,1749159018.069 [DockingStepper](INFO): Starting 2025-06-05T21:30:18.684Z,1749159018.684 [DockingStepper](INFO): Starting 2025-06-05T21:30:18.736Z,1749159018.736 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2025-06-05T21:30:19.004Z,1749159019.004 [DockingStepper](INFO): Starting 2025-06-05T21:30:19.249Z,1749159019.249 [DockingStepper](INFO): Starting 2025-06-05T21:30:19.657Z,1749159019.657 [DockingStepper](INFO): Starting 2025-06-05T21:30:20.157Z,1749159020.157 [DockingStepper](INFO): Starting 2025-06-05T21:30:20.482Z,1749159020.482 [DockingStepper](INFO): Starting 2025-06-05T21:30:20.516Z,1749159020.516 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-06-05T21:30:20.865Z,1749159020.865 [DockingStepper](INFO): Starting 2025-06-05T21:30:24.672Z,1749159024.672 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:30:36.993Z,1749159036.993 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2025-06-05T21:30:37.482Z,1749159037.482 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-06-05T21:30:37.792Z,1749159037.792 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:30:37.801Z,1749159037.801 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-06-05T21:30:37.801Z,1749159037.801 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-06-05T21:30:37.802Z,1749159037.802 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-06-05T21:30:37.802Z,1749159037.802 [DefaultWithUndock:LeaveDock] Stopped 2025-06-05T21:30:37.802Z,1749159037.802 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-06-05T21:30:37.802Z,1749159037.802 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-06-05T21:30:37.802Z,1749159037.802 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-06-05T21:30:37.802Z,1749159037.802 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-06-05T21:30:37.802Z,1749159037.802 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-05T21:30:37.803Z,1749159037.803 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-05T21:30:37.803Z,1749159037.803 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-05T21:30:37.803Z,1749159037.803 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-05T21:30:37.804Z,1749159037.804 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-05T21:30:37.804Z,1749159037.804 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-05T21:30:37.804Z,1749159037.804 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-05T21:30:38.217Z,1749159038.217 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-06-05T21:30:38.217Z,1749159038.217 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:30:38.217Z,1749159038.217 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-06-05T21:30:38.361Z,1749159038.361 [DockingStepper](INFO): Pause 2025-06-05T21:30:40.241Z,1749159040.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213040.00,A,3654.18622,N,12206.58054,W,1.147,98.81,050625,,,A*47 2025-06-05T21:30:40.243Z,1749159040.243 [NAL9602](INFO): GPS fix at 20250605T213040: (36.903104, -122.109676) 2025-06-05T21:30:40.289Z,1749159040.289 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-06-05T21:30:40.289Z,1749159040.289 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-06-05T21:30:46.091Z,1749159046.091 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250605T210525/Courier0013.lzma 2025-06-05T21:30:47.094Z,1749159047.094 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Courier0013.lzma.bak 2025-06-05T21:30:47.094Z,1749159047.094 [DataOverHttps](INFO): SBD MOMSN=25109401 2025-06-05T21:31:05.740Z,1749159065.740 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20250605T210525/Express0014.lzma 2025-06-05T21:31:06.742Z,1749159066.742 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0014.lzma.bak 2025-06-05T21:31:06.742Z,1749159066.742 [DataOverHttps](INFO): SBD MOMSN=25109404 2025-06-05T21:31:08.336Z,1749159068.336 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-06-05T21:31:08.336Z,1749159068.336 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-06-05T21:31:08.336Z,1749159068.336 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:31:18.688Z,1749159078.688 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:31:27.464Z,1749159087.464 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:31:36.180Z,1749159096.180 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:31:48.804Z,1749159108.804 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:31:57.620Z,1749159117.620 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:32:10.956Z,1749159130.956 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:32:20.361Z,1749159140.361 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:32:29.116Z,1749159149.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:32:37.949Z,1749159157.949 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:32:46.836Z,1749159166.836 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:32:55.616Z,1749159175.616 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:33:04.600Z,1749159184.600 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:33:13.372Z,1749159193.372 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:33:14.011Z,1749159194.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=48150, MT Status=2, MTMSN=0 2025-06-05T21:33:14.012Z,1749159194.012 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:33:22.176Z,1749159202.176 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:33:30.952Z,1749159210.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:33:40.772Z,1749159220.772 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:33:50.456Z,1749159230.456 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:33:50.746Z,1749159230.746 [NAL9602](INFO): SBD MO Status=2, MOMSN=48150, MT Status=2, MTMSN=0 2025-06-05T21:33:50.746Z,1749159230.746 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:33:59.252Z,1749159239.252 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:34:11.508Z,1749159251.508 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:34:13.051Z,1749159253.051 [NAL9602](INFO): SBD MO Status=1, MOMSN=48150, MT Status=0, MTMSN=0 2025-06-05T21:34:13.051Z,1749159253.051 [NAL9602](INFO): No messages in MT queue 2025-06-05T21:34:20.800Z,1749159260.800 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:34:29.516Z,1749159269.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:34:43.714Z,1749159283.714 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-05T21:34:44.364Z,1749159284.364 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:34:54.029Z,1749159294.029 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:35:05.912Z,1749159305.912 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:35:18.768Z,1749159318.768 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:35:28.516Z,1749159328.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:35:37.296Z,1749159337.296 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:35:46.084Z,1749159346.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:35:56.780Z,1749159356.780 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:36:07.244Z,1749159367.244 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:36:09.019Z,1749159369.019 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-05T21:36:09.019Z,1749159369.019 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-06-05T21:36:09.019Z,1749159369.019 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-05T21:36:09.019Z,1749159369.019 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-06-05T21:36:09.443Z,1749159369.443 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-06-05T21:36:09.456Z,1749159369.456 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-06-05T21:36:09.876Z,1749159369.876 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 6.393105 min 2025-06-05T21:36:09.876Z,1749159369.876 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-06-05T21:36:09.877Z,1749159369.877 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:36:09.877Z,1749159369.877 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-06-05T21:36:09.877Z,1749159369.877 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:36:09.877Z,1749159369.877 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2025-06-05T21:36:09.877Z,1749159369.877 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2025-06-05T21:36:09.877Z,1749159369.877 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:36:09.877Z,1749159369.877 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-06-05T21:36:11.914Z,1749159371.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213612.00,A,3654.18796,N,12206.48884,W,1.069,85.11,050625,,,A*44 2025-06-05T21:36:11.932Z,1749159371.932 [NAL9602](INFO): GPS fix at 20250605T213612: (36.903133, -122.108147) 2025-06-05T21:36:12.037Z,1749159372.037 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-06-05T21:36:12.037Z,1749159372.037 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-06-05T21:36:16.065Z,1749159376.065 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250605T210525/Courier0016.lzma 2025-06-05T21:36:17.834Z,1749159377.834 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Courier0016.lzma.bak 2025-06-05T21:36:17.834Z,1749159377.834 [DataOverHttps](INFO): SBD MOMSN=25109419 2025-06-05T21:36:27.568Z,1749159387.568 [NAL9602](INFO): SBD MO Status=1, MOMSN=48151, MT Status=0, MTMSN=0 2025-06-05T21:36:27.568Z,1749159387.568 [NAL9602](INFO): No messages in MT queue 2025-06-05T21:36:38.605Z,1749159398.605 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20250605T210525/Express0017.lzma 2025-06-05T21:36:39.606Z,1749159399.606 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0017.lzma.bak 2025-06-05T21:36:39.606Z,1749159399.606 [DataOverHttps](INFO): SBD MOMSN=25109422 2025-06-05T21:36:40.492Z,1749159400.492 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5. 2025-06-05T21:36:40.496Z,1749159400.496 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-06-05T21:36:40.922Z,1749159400.922 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-06-05T21:36:40.922Z,1749159400.922 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-06-05T21:36:40.922Z,1749159400.922 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:36:49.284Z,1749159409.284 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:36:57.989Z,1749159417.989 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:36:58.277Z,1749159418.277 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-05T21:37:06.660Z,1749159426.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:37:15.320Z,1749159435.320 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:37:24.060Z,1749159444.060 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:37:32.716Z,1749159452.716 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:37:41.444Z,1749159461.444 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:37:50.132Z,1749159470.132 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:38:00.005Z,1749159480.005 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:38:10.525Z,1749159490.525 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:38:21.464Z,1749159501.464 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:38:30.352Z,1749159510.352 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:38:39.592Z,1749159519.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:38:48.284Z,1749159528.284 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:38:56.889Z,1749159536.889 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:39:05.554Z,1749159545.554 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:39:14.344Z,1749159554.344 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:39:23.945Z,1749159563.945 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:39:32.672Z,1749159572.672 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:39:41.472Z,1749159581.472 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:39:50.280Z,1749159590.280 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:39:58.861Z,1749159598.861 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:40:08.010Z,1749159608.010 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:40:20.036Z,1749159620.036 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:40:28.600Z,1749159628.600 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:40:38.292Z,1749159638.292 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:40:47.184Z,1749159647.184 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:40:55.888Z,1749159655.888 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:40:56.343Z,1749159656.343 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25060513421295,35.0, +15.6, 0.0,1508.6,A, 0.00, 0.00, 658.78, 0.00, 0.00 2025-06-05T21:41:04.736Z,1749159664.736 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:41:13.373Z,1749159673.373 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:41:22.172Z,1749159682.172 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:41:30.780Z,1749159690.780 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:41:39.484Z,1749159699.484 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:41:41.592Z,1749159701.592 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-05T21:41:41.592Z,1749159701.592 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-06-05T21:41:41.592Z,1749159701.592 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-05T21:41:41.592Z,1749159701.592 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-06-05T21:41:42.073Z,1749159702.073 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-06-05T21:41:42.073Z,1749159702.073 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-06-05T21:41:42.392Z,1749159702.392 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 11.936931 min 2025-06-05T21:41:42.392Z,1749159702.392 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-06-05T21:41:42.392Z,1749159702.392 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:41:42.392Z,1749159702.392 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-06-05T21:41:42.392Z,1749159702.392 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:41:42.392Z,1749159702.392 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3 2025-06-05T21:41:42.393Z,1749159702.393 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3 2025-06-05T21:41:42.393Z,1749159702.393 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:41:42.393Z,1749159702.393 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-06-05T21:41:44.399Z,1749159704.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214144.00,A,3654.18551,N,12206.39721,W,0.525,90.58,050625,,,D*48 2025-06-05T21:41:44.401Z,1749159704.401 [NAL9602](INFO): GPS fix at 20250605T214144: (36.903092, -122.106620) 2025-06-05T21:41:44.448Z,1749159704.448 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-06-05T21:41:44.448Z,1749159704.448 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-06-05T21:41:48.492Z,1749159708.492 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250605T210525/Courier0019.lzma 2025-06-05T21:41:49.494Z,1749159709.494 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Courier0019.lzma.bak 2025-06-05T21:41:49.494Z,1749159709.494 [DataOverHttps](INFO): SBD MOMSN=25109427 2025-06-05T21:42:08.644Z,1749159728.644 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250605T210525/Express0020.lzma 2025-06-05T21:42:09.646Z,1749159729.646 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0020.lzma.bak 2025-06-05T21:42:09.646Z,1749159729.646 [DataOverHttps](INFO): SBD MOMSN=25109430 2025-06-05T21:42:11.225Z,1749159731.225 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-06-05T21:42:11.225Z,1749159731.225 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-06-05T21:42:11.225Z,1749159731.225 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:42:20.964Z,1749159740.964 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:42:29.704Z,1749159749.704 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:42:38.484Z,1749159758.484 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:42:40.270Z,1749159760.270 [NAL9602](INFO): SBD MO Status=2, MOMSN=48152, MT Status=2, MTMSN=0 2025-06-05T21:42:40.270Z,1749159760.270 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:42:47.356Z,1749159767.356 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:42:56.088Z,1749159776.088 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:04.648Z,1749159784.648 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:13.336Z,1749159793.336 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:16.234Z,1749159796.234 [NAL9602](INFO): SBD MO Status=2, MOMSN=48152, MT Status=2, MTMSN=0 2025-06-05T21:43:16.234Z,1749159796.234 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-05T21:43:22.028Z,1749159802.028 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:28.347Z,1749159808.347 [NAL9602](INFO): SBD MO Status=1, MOMSN=48152, MT Status=0, MTMSN=0 2025-06-05T21:43:28.347Z,1749159808.347 [NAL9602](INFO): No messages in MT queue 2025-06-05T21:43:28.350Z,1749159808.350 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS00, 0.00 2025-06-05T21:43:30.792Z,1749159810.792 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:39.532Z,1749159819.532 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:48.220Z,1749159828.220 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:56.756Z,1749159836.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:43:59.053Z,1749159839.053 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-05T21:44:05.604Z,1749159845.604 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:44:17.580Z,1749159857.580 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:44:26.640Z,1749159866.640 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:44:35.404Z,1749159875.404 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:44:44.072Z,1749159884.072 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:44:52.765Z,1749159892.765 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:45:01.640Z,1749159901.640 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:45:10.308Z,1749159910.308 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:45:18.860Z,1749159918.860 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:45:27.564Z,1749159927.564 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:45:36.361Z,1749159936.361 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:45:44.948Z,1749159944.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:45:53.736Z,1749159953.736 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:46:02.664Z,1749159962.664 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:46:14.448Z,1749159974.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:46:23.852Z,1749159983.852 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:46:32.588Z,1749159992.588 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:46:41.332Z,1749160001.332 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:46:53.064Z,1749160013.064 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:47:11.500Z,1749160031.500 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:47:12.031Z,1749160032.031 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-05T21:47:12.031Z,1749160032.031 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-06-05T21:47:12.031Z,1749160032.031 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-05T21:47:12.032Z,1749160032.032 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-06-05T21:47:12.388Z,1749160032.388 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-06-05T21:47:12.388Z,1749160032.388 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-06-05T21:47:12.753Z,1749160032.753 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 17.442108 min 2025-06-05T21:47:12.753Z,1749160032.753 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-06-05T21:47:12.753Z,1749160032.753 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:47:12.753Z,1749160032.753 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-06-05T21:47:12.753Z,1749160032.753 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:47:12.754Z,1749160032.754 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4 2025-06-05T21:47:12.754Z,1749160032.754 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4 2025-06-05T21:47:12.754Z,1749160032.754 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-05T21:47:12.754Z,1749160032.754 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-06-05T21:47:14.747Z,1749160034.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214715.00,A,3654.17803,N,12206.30565,W,0.505,104.69,050625,,,D*78 2025-06-05T21:47:14.749Z,1749160034.749 [NAL9602](INFO): GPS fix at 20250605T214715: (36.902967, -122.105094) 2025-06-05T21:47:14.809Z,1749160034.809 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-06-05T21:47:14.809Z,1749160034.809 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-06-05T21:47:23.360Z,1749160043.360 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250605T210525/Courier0022.lzma 2025-06-05T21:47:24.362Z,1749160044.362 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Courier0022.lzma.bak 2025-06-05T21:47:24.362Z,1749160044.362 [DataOverHttps](INFO): SBD MOMSN=25109434 2025-06-05T21:47:41.744Z,1749160061.744 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250605T210525/Express0023.lzma 2025-06-05T21:47:44.277Z,1749160064.277 [DataOverHttps](INFO): Moved sent file to Logs/20250605T210525/Express0023.lzma.bak 2025-06-05T21:47:44.277Z,1749160064.277 [DataOverHttps](INFO): SBD MOMSN=25109437 2025-06-05T21:47:44.652Z,1749160064.652 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-06-05T21:47:44.652Z,1749160064.652 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-06-05T21:47:44.652Z,1749160064.652 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-05T21:47:52.892Z,1749160072.892 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:48:01.712Z,1749160081.712 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:48:12.665Z,1749160092.665 [DataOverHttps](IMPORTANT): SBD MTMSN=20250605T214812 2025-06-05T21:48:20.875Z,1749160100.875 [DataOverHttps](INFO): Received command: restart sys 2025-06-05T21:48:20.937Z,1749160100.937 [CommandExec](IMPORTANT): got command restart system 2025-06-05T21:48:22.880Z,1749160102.880 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-05T21:48:23.044Z,1749160103.044 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-06-05T21:48:23.044Z,1749160103.044 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-05T21:48:23.045Z,1749160103.045 [CommandExec](INFO): Uninitializing the command executive. 2025-06-05T21:48:23.045Z,1749160103.045 [CommandExec](INFO): Uninitializing the command scheduler. 2025-06-05T21:48:23.045Z,1749160103.045 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.144Z,1749160103.144 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-06-05T21:48:23.144Z,1749160103.144 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-06-05T21:48:23.144Z,1749160103.144 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.145Z,1749160103.145 [NavChartDb](INFO): Join timeout helper Thread ID is 1230 2025-06-05T21:48:23.380Z,1749160103.380 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-05T21:48:23.380Z,1749160103.380 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.400Z,1749160103.400 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2025-06-05T21:48:23.400Z,1749160103.400 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.400Z,1749160103.400 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1231 2025-06-05T21:48:23.412Z,1749160103.412 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-05T21:48:23.412Z,1749160103.412 [WetLabsUBAT](INFO): Powering down 2025-06-05T21:48:23.413Z,1749160103.413 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.420Z,1749160103.420 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-06-05T21:48:23.420Z,1749160103.420 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.420Z,1749160103.420 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1232 2025-06-05T21:48:23.748Z,1749160103.748 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-05T21:48:23.749Z,1749160103.749 [WetLabsBB2FL](INFO): Powering down 2025-06-05T21:48:23.749Z,1749160103.749 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.752Z,1749160103.752 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-06-05T21:48:23.752Z,1749160103.752 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-06-05T21:48:23.752Z,1749160103.752 [CTD_Seabird](INFO): Join timeout