2026-03-11T18:04:06.037Z,1773252246.037 [Supervisor](DEBUG): Initializing supervisor. 2026-03-11T18:04:06.041Z,1773252246.041 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2026-03-11T18:04:06.042Z,1773252246.042 [SyncHandler](INFO): Protected caller Thread ID is 836 2026-03-11T18:04:06.042Z,1773252246.042 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2026-03-11T18:04:06.043Z,1773252246.043 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2026-03-11T18:04:06.044Z,1773252246.044 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837 2026-03-11T18:04:06.048Z,1773252246.048 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2026-03-11T18:04:06.067Z,1773252246.067 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2026-03-11T18:04:06.068Z,1773252246.068 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2026-03-11T18:04:06.068Z,1773252246.068 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838 2026-03-11T18:04:06.072Z,1773252246.072 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2026-03-11T18:04:06.073Z,1773252246.073 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2026-03-11T18:04:06.074Z,1773252246.074 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839 2026-03-11T18:04:06.076Z,1773252246.076 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2026-03-11T18:04:06.077Z,1773252246.077 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2026-03-11T18:04:06.077Z,1773252246.077 [logger ThreadHandler](INFO): Protected caller Thread ID is 840 2026-03-11T18:04:06.081Z,1773252246.081 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2026-03-11T18:04:06.081Z,1773252246.081 [Supervisor](INFO): Looking for Config files in directory: Config/ 2026-03-11T18:04:06.086Z,1773252246.086 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2026-03-11T18:04:06.558Z,1773252246.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2026-03-11T18:04:06.560Z,1773252246.560 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2026-03-11T18:04:06.774Z,1773252246.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2026-03-11T18:04:06.775Z,1773252246.775 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2026-03-11T18:04:06.874Z,1773252246.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2026-03-11T18:04:06.876Z,1773252246.876 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2026-03-11T18:04:07.126Z,1773252247.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2026-03-11T18:04:07.128Z,1773252247.128 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2026-03-11T18:04:07.216Z,1773252247.216 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2026-03-11T18:04:07.320Z,1773252247.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2026-03-11T18:04:07.320Z,1773252247.320 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2026-03-11T18:04:07.673Z,1773252247.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2026-03-11T18:04:07.675Z,1773252247.675 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2026-03-11T18:04:07.816Z,1773252247.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2026-03-11T18:04:07.816Z,1773252247.816 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2026-03-11T18:04:08.358Z,1773252248.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2026-03-11T18:04:08.359Z,1773252248.359 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2026-03-11T18:04:08.544Z,1773252248.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2026-03-11T18:04:08.546Z,1773252248.546 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2026-03-11T18:04:08.966Z,1773252248.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2026-03-11T18:04:08.966Z,1773252248.966 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2026-03-11T18:04:09.286Z,1773252249.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2026-03-11T18:04:09.820Z,1773252249.820 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2026-03-11T18:04:10.136Z,1773252250.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2026-03-11T18:04:10.137Z,1773252250.137 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2026-03-11T18:04:10.972Z,1773252250.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2026-03-11T18:04:10.974Z,1773252250.974 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2026-03-11T18:04:11.320Z,1773252251.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2026-03-11T18:04:11.322Z,1773252251.322 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2026-03-11T18:04:11.568Z,1773252251.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2026-03-11T18:04:11.569Z,1773252251.569 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2026-03-11T18:04:11.573Z,1773252251.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2026-03-11T18:04:11.703Z,1773252251.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2026-03-11T18:04:11.796Z,1773252251.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2026-03-11T18:04:11.940Z,1773252251.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2026-03-11T18:04:12.034Z,1773252252.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2026-03-11T18:04:12.303Z,1773252252.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2026-03-11T18:04:12.305Z,1773252252.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2026-03-11T18:04:12.417Z,1773252252.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2026-03-11T18:04:12.531Z,1773252252.531 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2026-03-11T18:04:12.638Z,1773252252.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2026-03-11T18:04:12.798Z,1773252252.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2026-03-11T18:04:12.903Z,1773252252.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2026-03-11T18:04:12.998Z,1773252252.998 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2026-03-11T18:04:13.009Z,1773252253.009 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2026-03-11T18:04:13.025Z,1773252253.025 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2026-03-11T18:04:13.026Z,1773252253.026 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2026-03-11T18:04:13.194Z,1773252253.194 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2026-03-11T18:04:13.196Z,1773252253.196 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2026-03-11T18:04:13.270Z,1773252253.270 [VerticalControl](DEBUG): Construct VerticalControl. 2026-03-11T18:04:13.330Z,1773252253.330 [VerticalControl] Loaded 2026-03-11T18:04:13.330Z,1773252253.330 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2026-03-11T18:04:13.333Z,1773252253.333 [HorizontalControl](DEBUG): Construct HorizontalControl. 2026-03-11T18:04:13.379Z,1773252253.379 [HorizontalControl] Loaded 2026-03-11T18:04:13.380Z,1773252253.380 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2026-03-11T18:04:13.382Z,1773252253.382 [SpeedControl](DEBUG): Construct SpeedControl. 2026-03-11T18:04:13.385Z,1773252253.385 [SpeedControl] Loaded 2026-03-11T18:04:13.385Z,1773252253.385 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2026-03-11T18:04:13.388Z,1773252253.388 [LoopControl](DEBUG): Construct LoopControl. 2026-03-11T18:04:13.388Z,1773252253.388 [LoopControl] Loaded 2026-03-11T18:04:13.389Z,1773252253.389 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2026-03-11T18:04:13.389Z,1773252253.389 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2026-03-11T18:04:13.390Z,1773252253.390 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2026-03-11T18:04:13.575Z,1773252253.575 [BuoyancyServo] Loaded 2026-03-11T18:04:13.576Z,1773252253.576 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2026-03-11T18:04:13.597Z,1773252253.597 [ElevatorServo] Loaded 2026-03-11T18:04:13.597Z,1773252253.597 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2026-03-11T18:04:13.618Z,1773252253.618 [MassServo] Loaded 2026-03-11T18:04:13.618Z,1773252253.618 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2026-03-11T18:04:13.638Z,1773252253.638 [RudderServo] Loaded 2026-03-11T18:04:13.638Z,1773252253.638 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2026-03-11T18:04:13.655Z,1773252253.655 [ThrusterHE] Loaded 2026-03-11T18:04:13.655Z,1773252253.655 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2026-03-11T18:04:13.655Z,1773252253.655 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2026-03-11T18:04:13.656Z,1773252253.656 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2026-03-11T18:04:13.726Z,1773252253.726 [DepthRateCalculator] Loaded 2026-03-11T18:04:13.726Z,1773252253.726 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2026-03-11T18:04:13.731Z,1773252253.731 [PitchRateCalculator] Loaded 2026-03-11T18:04:13.731Z,1773252253.731 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2026-03-11T18:04:13.741Z,1773252253.741 [SpeedCalculator] Loaded 2026-03-11T18:04:13.741Z,1773252253.741 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2026-03-11T18:04:13.745Z,1773252253.745 [YawRateCalculator] Loaded 2026-03-11T18:04:13.746Z,1773252253.746 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2026-03-11T18:04:13.764Z,1773252253.764 [ElevatorOffsetCalculator] Loaded 2026-03-11T18:04:13.764Z,1773252253.764 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2026-03-11T18:04:13.765Z,1773252253.765 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2026-03-11T18:04:13.765Z,1773252253.765 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2026-03-11T18:04:13.905Z,1773252253.905 [DeadReckonUsingMultipleVelocitySources] Loaded 2026-03-11T18:04:13.905Z,1773252253.905 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2026-03-11T18:04:13.919Z,1773252253.919 [NavChart] Loaded 2026-03-11T18:04:13.919Z,1773252253.919 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2026-03-11T18:04:13.924Z,1773252253.924 [UniversalFixResidualReporter] Loaded 2026-03-11T18:04:13.925Z,1773252253.925 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2026-03-11T18:04:13.925Z,1773252253.925 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2026-03-11T18:04:13.926Z,1773252253.926 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2026-03-11T18:04:15.331Z,1773252255.331 [AHRS_M2] Loaded 2026-03-11T18:04:15.331Z,1773252255.331 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2026-03-11T18:04:15.587Z,1773252255.587 [BackseatComponent] Loaded 2026-03-11T18:04:15.587Z,1773252255.587 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2026-03-11T18:04:15.588Z,1773252255.588 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409454E0 2026-03-11T18:04:15.588Z,1773252255.588 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922 2026-03-11T18:04:15.591Z,1773252255.591 [LcmPublisher] Loaded 2026-03-11T18:04:15.592Z,1773252255.592 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2026-03-11T18:04:15.598Z,1773252255.598 [BioacousticsDataBridge] Loaded 2026-03-11T18:04:15.598Z,1773252255.598 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2026-03-11T18:04:16.410Z,1773252256.410 [BPC1] Loaded 2026-03-11T18:04:16.410Z,1773252256.410 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2026-03-11T18:04:16.507Z,1773252256.507 [DAT] Loaded 2026-03-11T18:04:16.507Z,1773252256.507 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2026-03-11T18:04:16.508Z,1773252256.508 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409754E0 2026-03-11T18:04:16.509Z,1773252256.509 [DAT ThreadHandler](INFO): Protected caller Thread ID is 923 2026-03-11T18:04:16.648Z,1773252256.648 [DataOverHttps] Loaded 2026-03-11T18:04:16.649Z,1773252256.649 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2026-03-11T18:04:16.650Z,1773252256.650 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0 2026-03-11T18:04:16.650Z,1773252256.650 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2026-03-11T18:04:16.673Z,1773252256.673 [Depth_Keller] Loaded 2026-03-11T18:04:16.673Z,1773252256.673 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2026-03-11T18:04:16.678Z,1773252256.678 [DropWeight] Loaded 2026-03-11T18:04:16.678Z,1773252256.678 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2026-03-11T18:04:16.741Z,1773252256.741 [NAL9602] Loaded 2026-03-11T18:04:16.741Z,1773252256.741 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2026-03-11T18:04:16.770Z,1773252256.770 [Onboard] Loaded 2026-03-11T18:04:16.771Z,1773252256.771 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2026-03-11T18:04:16.772Z,1773252256.772 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D54E0 2026-03-11T18:04:16.772Z,1773252256.772 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2026-03-11T18:04:16.786Z,1773252256.786 [Power24vConverter] Loaded 2026-03-11T18:04:16.786Z,1773252256.786 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2026-03-11T18:04:16.804Z,1773252256.804 [Radio_Surface] Loaded 2026-03-11T18:04:16.804Z,1773252256.804 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2026-03-11T18:04:16.805Z,1773252256.805 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A054E0 2026-03-11T18:04:16.805Z,1773252256.805 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2026-03-11T18:04:16.819Z,1773252256.819 [Sonardyne_Nano] Loaded 2026-03-11T18:04:16.819Z,1773252256.819 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2026-03-11T18:04:16.842Z,1773252256.842 [Waterlinked] Loaded 2026-03-11T18:04:16.842Z,1773252256.842 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2026-03-11T18:04:16.843Z,1773252256.843 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2026-03-11T18:04:16.844Z,1773252256.844 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2026-03-11T18:04:16.946Z,1773252256.946 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2026-03-11T18:04:16.946Z,1773252256.946 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2026-03-11T18:04:17.206Z,1773252257.206 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2026-03-11T18:04:17.207Z,1773252257.207 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2026-03-11T18:04:17.256Z,1773252257.256 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2026-03-11T18:04:17.256Z,1773252257.256 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2026-03-11T18:04:17.476Z,1773252257.476 [CTD_Seabird] Loaded 2026-03-11T18:04:17.476Z,1773252257.476 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2026-03-11T18:04:17.477Z,1773252257.477 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B514E0 2026-03-11T18:04:17.478Z,1773252257.478 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2026-03-11T18:04:17.500Z,1773252257.500 [PAR_Licor] Loaded 2026-03-11T18:04:17.500Z,1773252257.500 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2026-03-11T18:04:17.544Z,1773252257.544 [WetLabsBB2FL] Loaded 2026-03-11T18:04:17.544Z,1773252257.544 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2026-03-11T18:04:17.545Z,1773252257.545 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B814E0 2026-03-11T18:04:17.546Z,1773252257.546 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2026-03-11T18:04:17.546Z,1773252257.546 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2026-03-11T18:04:17.547Z,1773252257.547 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2026-03-11T18:04:17.949Z,1773252257.949 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2026-03-11T18:04:17.950Z,1773252257.950 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2026-03-11T18:04:18.147Z,1773252258.147 [SBIT](DEBUG): Construct Startup Built In Test. 2026-03-11T18:04:18.156Z,1773252258.156 [SBIT] Loaded 2026-03-11T18:04:18.156Z,1773252258.156 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2026-03-11T18:04:18.159Z,1773252258.159 [IBIT](DEBUG): Construct Initiated Built In Test. 2026-03-11T18:04:18.172Z,1773252258.172 [IBIT] Loaded 2026-03-11T18:04:18.172Z,1773252258.172 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2026-03-11T18:04:18.178Z,1773252258.178 [CBIT](DEBUG): Construct Continuous Built In Test. 2026-03-11T18:04:18.257Z,1773252258.257 [CBIT] Loaded 2026-03-11T18:04:18.257Z,1773252258.257 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2026-03-11T18:04:18.289Z,1773252258.289 [GFScanner] Loaded 2026-03-11T18:04:18.289Z,1773252258.289 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2026-03-11T18:04:18.290Z,1773252258.290 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2026-03-11T18:04:18.296Z,1773252258.296 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2026-03-11T18:04:18.299Z,1773252258.299 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2026-03-11T18:04:18.310Z,1773252258.310 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2026-03-11T18:04:18.311Z,1773252258.311 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C8A4E0 2026-03-11T18:04:18.311Z,1773252258.311 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930 2026-03-11T18:04:18.316Z,1773252258.316 [Supervisor](INFO): Main Thread ID is 829 2026-03-11T18:04:18.316Z,1773252258.316 [Supervisor](DEBUG): Running supervisor. 2026-03-11T18:04:18.317Z,1773252258.317 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931 2026-03-11T18:04:18.317Z,1773252258.317 [CommandExec](INFO): Initializing the command executive. 2026-03-11T18:04:18.319Z,1773252258.319 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932 2026-03-11T18:04:18.321Z,1773252258.321 [controlThread ThreadHandler](INFO): Handler Thread ID is 933 2026-03-11T18:04:18.322Z,1773252258.322 [controlThread](DEBUG): Initializing ControlThread 2026-03-11T18:04:18.322Z,1773252258.322 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2026-03-11T18:04:18.324Z,1773252258.324 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2026-03-11T18:04:18.325Z,1773252258.325 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2026-03-11T18:04:18.325Z,1773252258.325 [LoopControl](DEBUG): Initialize LoopControlComponent. 2026-03-11T18:04:18.327Z,1773252258.327 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2026-03-11T18:04:18.327Z,1773252258.327 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2026-03-11T18:04:18.327Z,1773252258.327 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2026-03-11T18:04:18.328Z,1773252258.328 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2026-03-11T18:04:18.328Z,1773252258.328 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2026-03-11T18:04:18.329Z,1773252258.329 [NavChart](DEBUG): Initialize NavChart Navigation. 2026-03-11T18:04:18.329Z,1773252258.329 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2026-03-11T18:04:18.336Z,1773252258.336 [SBIT](INFO): Initialize SBIT Component. 2026-03-11T18:04:18.336Z,1773252258.336 [SBIT](IMPORTANT): git: 2026-03-03 2026-03-11T18:04:18.337Z,1773252258.337 [SBIT](INFO): git hash: f7a2e94ac3e2178d8a146c43cd471ee86a10fe19 2026-03-11T18:04:18.337Z,1773252258.337 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2026-03-11T18:04:18.338Z,1773252258.338 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2026-03-11T18:04:18.339Z,1773252258.339 [SBIT](INFO): Beginning SBIT in 26.000000 seconds. 2026-03-11T18:04:18.340Z,1773252258.340 [IBIT](INFO): Initialize IBIT Component. 2026-03-11T18:04:18.340Z,1773252258.340 [CBIT](DEBUG): Initialize CBIT Component. 2026-03-11T18:04:18.341Z,1773252258.341 [logger ThreadHandler](INFO): Handler Thread ID is 934 2026-03-11T18:04:18.352Z,1773252258.352 [CBIT](DEBUG): Initialized mux pins. 2026-03-11T18:04:18.352Z,1773252258.352 [CBIT](DEBUG): Initializing the watchdog timer. 2026-03-11T18:04:18.360Z,1773252258.360 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935 2026-03-11T18:04:18.372Z,1773252258.372 [DAT ThreadHandler](INFO): Handler Thread ID is 936 2026-03-11T18:04:18.373Z,1773252258.373 [DAT](INFO): Powering up 2026-03-11T18:04:18.373Z,1773252258.373 [DAT](DEBUG): Initializing DAT. 2026-03-11T18:04:18.376Z,1773252258.376 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2026-03-11T18:04:18.376Z,1773252258.376 [CBIT](DEBUG): Initializing heartbeat. 2026-03-11T18:04:18.378Z,1773252258.378 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938 2026-03-11T18:04:18.380Z,1773252258.380 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2026-03-11T18:04:18.388Z,1773252258.388 [Onboard ThreadHandler](INFO): Handler Thread ID is 939 2026-03-11T18:04:18.405Z,1773252258.405 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940 2026-03-11T18:04:18.425Z,1773252258.425 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941 2026-03-11T18:04:18.425Z,1773252258.425 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2026-03-11T18:04:18.429Z,1773252258.429 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943 2026-03-11T18:04:18.432Z,1773252258.432 [WetLabsBB2FL](INFO): Powering up 2026-03-11T18:04:18.433Z,1773252258.433 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945 2026-03-11T18:04:18.441Z,1773252258.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000 2026-03-11T18:04:18.442Z,1773252258.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000 2026-03-11T18:04:18.442Z,1773252258.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000 2026-03-11T18:04:18.442Z,1773252258.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000 2026-03-11T18:04:18.442Z,1773252258.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000 2026-03-11T18:04:18.442Z,1773252258.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000 2026-03-11T18:04:18.442Z,1773252258.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000 2026-03-11T18:04:18.443Z,1773252258.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000 2026-03-11T18:04:18.443Z,1773252258.443 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000 2026-03-11T18:04:18.443Z,1773252258.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000 2026-03-11T18:04:18.443Z,1773252258.443 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000 2026-03-11T18:04:18.443Z,1773252258.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000 2026-03-11T18:04:18.444Z,1773252258.444 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000 2026-03-11T18:04:18.444Z,1773252258.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000 2026-03-11T18:04:18.444Z,1773252258.444 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000 2026-03-11T18:04:18.444Z,1773252258.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000 2026-03-11T18:04:18.444Z,1773252258.444 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000 2026-03-11T18:04:18.444Z,1773252258.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000 2026-03-11T18:04:18.445Z,1773252258.445 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000 2026-03-11T18:04:18.445Z,1773252258.445 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000 2026-03-11T18:04:18.447Z,1773252258.447 [CBIT](DEBUG): Deactivating emergency mode. 2026-03-11T18:04:18.487Z,1773252258.487 [CBIT](DEBUG): Backplane powered. 2026-03-11T18:04:18.488Z,1773252258.488 [GFScanner](DEBUG): Initializing GFScanner 2026-03-11T18:04:18.488Z,1773252258.488 [GFScanner](DEBUG): Deactivating GF circuits. 2026-03-11T18:04:18.493Z,1773252258.493 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2026-03-11T18:04:18.493Z,1773252258.493 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2026-03-11T18:04:18.494Z,1773252258.494 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2026-03-11T18:04:18.504Z,1773252258.504 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2026-03-11T18:04:18.555Z,1773252258.555 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2026-03-11T18:04:18.575Z,1773252258.575 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2026-03-11T18:04:18.615Z,1773252258.615 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2026-03-11T18:04:18.615Z,1773252258.615 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2026-03-11T18:04:18.616Z,1773252258.616 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2026-03-11T18:04:18.625Z,1773252258.625 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2026-03-11T18:04:18.812Z,1773252258.812 [Radio_Surface](INFO): Powering up 2026-03-11T18:04:18.917Z,1773252258.917 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2026-03-11T18:04:18.935Z,1773252258.935 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2026-03-11T18:04:18.937Z,1773252258.937 [Default:A.Wait](DEBUG): Construct Wait. 2026-03-11T18:04:18.939Z,1773252258.939 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2026-03-11T18:04:18.984Z,1773252258.984 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2026-03-11T18:04:18.987Z,1773252258.987 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2026-03-11T18:04:19.009Z,1773252259.009 [Default:E.Execute](DEBUG): Construct Execute. 2026-03-11T18:04:19.028Z,1773252259.028 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2026-03-11T18:04:19.033Z,1773252259.033 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter, 2026-03-11T18:04:19.056Z,1773252259.056 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2026-03-11T18:04:19.176Z,1773252259.176 [Depth_Keller](INFO): Initializing. 2026-03-11T18:04:19.178Z,1773252259.178 [Power24vConverter](INFO): Powering up. 2026-03-11T18:04:19.178Z,1773252259.178 [Sonardyne_Nano](INFO): Initializing. 2026-03-11T18:04:19.224Z,1773252259.224 [DepthRateCalculator](ERROR): Depth measurement is not active 2026-03-11T18:04:19.251Z,1773252259.251 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2026-03-11T18:04:19.268Z,1773252259.268 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2026-03-11T18:04:19.269Z,1773252259.269 [ElevatorServo](DEBUG): Initializing EZServoServo. 2026-03-11T18:04:19.276Z,1773252259.276 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2026-03-11T18:04:19.277Z,1773252259.277 [MassServo](DEBUG): Initializing EZServoServo. 2026-03-11T18:04:19.284Z,1773252259.284 [MassServo](DEBUG): Initializing MassServo. 2026-03-11T18:04:19.285Z,1773252259.285 [RudderServo](DEBUG): Initializing EZServoServo. 2026-03-11T18:04:19.292Z,1773252259.292 [RudderServo](DEBUG): Initializing RudderServo. 2026-03-11T18:04:19.293Z,1773252259.293 [ThrusterHE](DEBUG): Initializing EZServoServo. 2026-03-11T18:04:19.300Z,1773252259.300 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2026-03-11T18:04:21.076Z,1773252261.076 [WetLabsBB2FL](INFO): Powering down 2026-03-11T18:04:21.466Z,1773252261.466 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2026-03-11T18:04:22.716Z,1773252262.716 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2026-03-11T18:04:23.119Z,1773252263.119 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2026-03-11T18:04:24.977Z,1773252264.977 [ThrusterHE](ERROR): Zero Speed Commanded. 2026-03-11T18:04:30.228Z,1773252270.228 [DAT](INFO): DAT read: 2026-03-11T18:04:30.230Z,1773252270.230 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2026-03-11T18:04:31.993Z,1773252271.993 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth 2026-03-11T18:04:31.994Z,1773252271.994 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1 2026-03-11T18:04:31.994Z,1773252271.994 [DAT](INFO): DAT read: Mar 11 2026 18:04:21 2026-03-11T18:04:32.749Z,1773252272.749 [DAT](INFO): DAT read: Features enabled [Bearing] 2026-03-11T18:04:32.750Z,1773252272.750 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2026-03-11T18:04:32.750Z,1773252272.750 [DAT](INFO): commRate: 800 2026-03-11T18:04:34.816Z,1773252274.816 [DAT](INFO): entering command mode 2026-03-11T18:04:35.016Z,1773252275.016 [DAT](INFO): DAT read: 2026-03-11T18:04:35.017Z,1773252275.017 [DAT](INFO): DAT read: user:1> 2026-03-11T18:04:35.017Z,1773252275.017 [DAT](INFO): setting verbose to 3 2026-03-11T18:04:35.268Z,1773252275.268 [DAT](INFO): DAT read: user:1> 2026-03-11T18:04:35.269Z,1773252275.269 [DAT](INFO): DAT read: Verbose | 3 2026-03-11T18:04:35.270Z,1773252275.270 [DAT](INFO): set verbose to 3 2026-03-11T18:04:35.270Z,1773252275.270 [DAT](INFO): setting DatVerbose to 27440 2026-03-11T18:04:35.520Z,1773252275.520 [DAT](INFO): DAT read: user:2> 2026-03-11T18:04:35.521Z,1773252275.521 [DAT](INFO): DAT read: DatVerbose | 27440 2026-03-11T18:04:35.522Z,1773252275.522 [DAT](INFO): set DatVerbose to 27440 2026-03-11T18:04:35.522Z,1773252275.522 [DAT](INFO): setting transmit power to 8 2026-03-11T18:04:35.772Z,1773252275.772 [DAT](INFO): DAT read: user:3> 2026-03-11T18:04:35.773Z,1773252275.773 [DAT](INFO): DAT read: TxPower | 8 (Max) 2026-03-11T18:04:35.773Z,1773252275.773 [DAT](INFO): set transmit power to 8 2026-03-11T18:04:35.774Z,1773252275.774 [DAT](INFO): setting local address to 12 2026-03-11T18:04:36.024Z,1773252276.024 [DAT](INFO): DAT read: user:4> 2026-03-11T18:04:36.025Z,1773252276.025 [DAT](INFO): DAT read: LocalAddr | 12 2026-03-11T18:04:36.025Z,1773252276.025 [DAT](INFO): set local address to 12 2026-03-11T18:04:36.026Z,1773252276.026 [DAT](INFO): Setting time to: 18:4:36 And date to:3/11/2026 2026-03-11T18:04:36.276Z,1773252276.276 [DAT](INFO): DAT read: user:5> 2026-03-11T18:04:36.277Z,1773252276.277 [DAT](INFO): DAT read: Wed Mar 11, 2026 18:04:36 2026-03-11T18:04:36.277Z,1773252276.277 [DAT](INFO): Local DAT time set to Wed Mar 11, 2026 18:04:36 2026-03-11T18:04:44.921Z,1773252284.921 [SBIT](IMPORTANT): Beginning Startup BIT 2026-03-11T18:04:44.926Z,1773252284.926 [GFScanner](IMPORTANT): Beginning ground fault scan 2026-03-11T18:04:46.930Z,1773252286.930 [NAL9602](INFO): Powering up NAL9602 2026-03-11T18:04:50.337Z,1773252290.337 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003477 CHAN A1 (24V): 0.004411 CHAN A2 (12V): -0.002323 CHAN A3 (5V): -0.005747 CHAN B0 (3.3V): -0.003541 CHAN B1 (3.15aV): -0.004574 CHAN B2 (3.15bV): -0.004821 CHAN B3 (GND): -0.007545 OPEN: 0.004850 Full Scale: +/- 1 mA 2026-03-11T18:04:58.110Z,1773252298.110 [NAL9602](INFO): NAL9602 initialized 2026-03-11T18:05:12.289Z,1773252312.289 [Waterlinked](ERROR): No DVL communication! Re-initializing 2026-03-11T18:05:12.289Z,1773252312.289 [Waterlinked] Communications Fault, FailCount= 1 2026-03-11T18:05:12.289Z,1773252312.289 [Waterlinked](ERROR): Communications Fault 2026-03-11T18:05:12.414Z,1773252312.414 [CBIT](ERROR): Communications Fault in component: Waterlinked 2026-03-11T18:05:12.748Z,1773252312.748 [Waterlinked](INFO): Powering down 2026-03-11T18:05:13.499Z,1773252313.499 [CBIT](INFO): Clearing failed state for component Waterlinked 2026-03-11T18:05:13.499Z,1773252313.499 [Waterlinked] No Fault, FailCount= 1 2026-03-11T18:05:24.763Z,1773252324.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=13037, MT Status=0, MTMSN=0 2026-03-11T18:05:24.763Z,1773252324.763 [NAL9602](INFO): No messages in MT queue 2026-03-11T18:05:38.555Z,1773252338.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180531.00,A,3648.15500,N,12147.22203,W,0.894,0.00,110326,,,A*78 2026-03-11T18:05:38.559Z,1773252338.559 [NAL9602](INFO): GPS fix at 20260311T180531: (36.802583, -121.787034) 2026-03-11T18:05:38.575Z,1773252338.575 [SBIT](IMPORTANT): SBIT PASSED 2026-03-11T18:05:38.576Z,1773252338.576 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2026-03-11T18:05:38.576Z,1773252338.576 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2026-03-11T18:05:38.576Z,1773252338.576 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter; 2026-03-11T18:05:38.576Z,1773252338.576 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter; 2026-03-11T18:05:38.577Z,1773252338.577 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=148.354027 cubic_centimeter; 2026-03-11T18:05:38.577Z,1773252338.577 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.731936 millimeter; 2026-03-11T18:05:38.995Z,1773252338.995 [MissionManager](IMPORTANT): Started mission Startup 2026-03-11T18:05:38.995Z,1773252338.995 [Startup] Running Loop=1 2026-03-11T18:05:38.996Z,1773252338.996 [Startup](DEBUG): Aggregate::initialize Startup 2026-03-11T18:05:38.996Z,1773252338.996 [Startup:A.GoToSurface] Running Loop=1 2026-03-11T18:05:38.996Z,1773252338.996 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-11T18:05:38.996Z,1773252338.996 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-03-11T18:05:38.997Z,1773252338.997 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-03-11T18:05:38.997Z,1773252338.997 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-03-11T18:05:38.997Z,1773252338.997 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-03-11T18:05:38.998Z,1773252338.998 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-11T18:05:38.998Z,1773252338.998 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-11T18:05:38.000Z,1773252339.000 [Startup:StartupSatComms] Running Loop=1 2026-03-11T18:05:38.000Z,1773252339.000 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2026-03-11T18:05:38.000Z,1773252339.000 [Startup:StartupSatComms:A] Running Loop=1 2026-03-11T18:05:39.436Z,1773252339.436 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2026-03-11T18:05:41.414Z,1773252341.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180534.00,A,3648.15514,N,12147.22175,W,1.089,18.66,110326,,,A*46 2026-03-11T18:05:41.421Z,1773252341.421 [NAL9602](INFO): GPS fix at 20260311T180534: (36.802586, -121.787029) 2026-03-11T18:05:41.513Z,1773252341.513 [Startup:StartupSatComms:A] Stopped 2026-03-11T18:05:41.514Z,1773252341.514 [Startup:StartupSatComms:B] Running Loop=1 2026-03-11T18:05:41.879Z,1773252341.879 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-03-11T18:05:56.813Z,1773252356.813 [NAL9602](INFO): SBD MO Status=1, MOMSN=13038, MT Status=0, MTMSN=0 2026-03-11T18:05:56.868Z,1773252356.868 [NAL9602](INFO): Sent 18 bytes from file Logs/20260310T222717/Courier0625.lzma 2026-03-11T18:05:56.869Z,1773252356.869 [NAL9602](INFO): Packets left to send: 0 2026-03-11T18:06:04.777Z,1773252364.777 [Waterlinked](ERROR): No DVL communication! Re-initializing 2026-03-11T18:06:04.777Z,1773252364.777 [Waterlinked] Communications Fault, FailCount= 1 2026-03-11T18:06:04.777Z,1773252364.777 [Waterlinked](ERROR): Communications Fault 2026-03-11T18:06:04.901Z,1773252364.901 [CBIT](ERROR): Communications Fault in component: Waterlinked 2026-03-11T18:06:05.244Z,1773252365.244 [Waterlinked](INFO): Powering down 2026-03-11T18:06:05.975Z,1773252365.975 [CBIT](INFO): Clearing failed state for component Waterlinked 2026-03-11T18:06:05.975Z,1773252365.975 [Waterlinked] No Fault, FailCount= 1 2026-03-11T18:06:11.198Z,1773252371.198 [NAL9602](INFO): SBD MO Status=2, MOMSN=13039, MT Status=2, MTMSN=0 2026-03-11T18:06:11.198Z,1773252371.198 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-11T18:06:41.906Z,1773252401.906 [Startup:StartupSatComms:B](INFO): Timed out from 2026-03-11T18:05:41.5Z 2026-03-11T18:06:41.906Z,1773252401.906 [Startup:StartupSatComms:B] Stopped 2026-03-11T18:06:41.906Z,1773252401.906 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2026-03-11T18:06:41.906Z,1773252401.906 [Startup:StartupSatComms] Stopped 2026-03-11T18:06:41.907Z,1773252401.907 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2026-03-11T18:06:41.909Z,1773252401.909 [Startup](INFO): Completed Startup 2026-03-11T18:06:41.909Z,1773252401.909 [MissionManager](INFO): Startup is completed. 2026-03-11T18:06:41.910Z,1773252401.910 [MissionManager](INFO): Uninitializing Mission Startup 2026-03-11T18:06:41.910Z,1773252401.910 [Startup] Stopped 2026-03-11T18:06:41.910Z,1773252401.910 [Startup](DEBUG): Aggregate::uninitialize Startup 2026-03-11T18:06:41.910Z,1773252401.910 [Startup:A.GoToSurface] Stopped 2026-03-11T18:06:41.910Z,1773252401.910 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-11T18:06:42.157Z,1773252402.157 [MissionManager](IMPORTANT): Started mission Default 2026-03-11T18:06:42.157Z,1773252402.157 [Default] Running Loop=1 2026-03-11T18:06:42.157Z,1773252402.157 [Default](DEBUG): Aggregate::initialize Default 2026-03-11T18:06:42.157Z,1773252402.157 [Default:B.GoToSurface] Running Loop=1 2026-03-11T18:06:42.158Z,1773252402.158 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-11T18:06:42.158Z,1773252402.158 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-03-11T18:06:42.159Z,1773252402.159 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-03-11T18:06:42.160Z,1773252402.160 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-03-11T18:06:42.161Z,1773252402.161 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-03-11T18:06:42.161Z,1773252402.161 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-11T18:06:42.162Z,1773252402.162 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-11T18:06:42.163Z,1773252402.163 [Default:A.Wait] Running Loop=1 2026-03-11T18:06:42.163Z,1773252402.163 [Default:A.Wait](DEBUG): Initialize Wait Component. 2026-03-11T18:06:55.413Z,1773252415.413 [Default:A.Wait](INFO): Done Waiting. 2026-03-11T18:06:55.414Z,1773252415.414 [Default:A.Wait] Stopped 2026-03-11T18:06:55.414Z,1773252415.414 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2026-03-11T18:06:55.831Z,1773252415.831 [Default:CheckIn] Running Loop=1 2026-03-11T18:06:55.831Z,1773252415.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-03-11T18:06:55.831Z,1773252415.831 [Default:CheckIn:Read_GPS] Running Loop=1 2026-03-11T18:06:56.217Z,1773252416.217 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-11T18:06:58.609Z,1773252418.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=13039, MT Status=2, MTMSN=0 2026-03-11T18:06:58.609Z,1773252418.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-11T18:06:59.841Z,1773252419.841 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180652.00,A,3648.16618,N,12147.19374,W,0.816,142.64,110326,,,A*71 2026-03-11T18:06:59.863Z,1773252419.863 [NAL9602](INFO): GPS fix at 20260311T180652: (36.802770, -121.786562) 2026-03-11T18:07:00.046Z,1773252420.046 [Default:CheckIn:Read_GPS] Stopped 2026-03-11T18:07:00.046Z,1773252420.046 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-03-11T18:07:00.419Z,1773252420.419 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-03-11T18:07:12.957Z,1773252432.957 [Waterlinked](ERROR): No DVL communication! Re-initializing 2026-03-11T18:07:12.957Z,1773252432.957 [Waterlinked] Communications Fault, FailCount= 1 2026-03-11T18:07:12.957Z,1773252432.957 [Waterlinked](ERROR): Communications Fault 2026-03-11T18:07:13.052Z,1773252433.052 [CBIT](ERROR): Communications Fault in component: Waterlinked 2026-03-11T18:07:13.408Z,1773252433.408 [Waterlinked](INFO): Powering down 2026-03-11T18:07:14.148Z,1773252434.148 [CBIT](INFO): Clearing failed state for component Waterlinked 2026-03-11T18:07:14.148Z,1773252434.148 [Waterlinked] No Fault, FailCount= 1 2026-03-11T18:07:24.613Z,1773252444.613 [NAL9602](INFO): SBD MO Status=2, MOMSN=13039, MT Status=2, MTMSN=0 2026-03-11T18:07:24.613Z,1773252444.613 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-11T18:07:46.535Z,1773252466.535 [NAL9602](INFO): SBD MO Status=1, MOMSN=13039, MT Status=0, MTMSN=0 2026-03-11T18:07:46.587Z,1773252466.587 [NAL9602](INFO): Sent 268 bytes from file Logs/20260311T180406/Courier0000.lzma 2026-03-11T18:07:46.587Z,1773252466.587 [NAL9602](INFO): Packets left to send: 0 2026-03-11T18:07:55.573Z,1773252475.573 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:07:58.590Z,1773252478.590 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009378 2026-03-11T18:08:01.153Z,1773252481.153 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:08:02.389Z,1773252482.389 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:08:03.666Z,1773252483.666 [NAL9602](INFO): SBD MO Status=2, MOMSN=13040, MT Status=2, MTMSN=0 2026-03-11T18:08:03.666Z,1773252483.666 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-11T18:08:09.271Z,1773252489.271 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260311T180406/Courier0004.lzma 2026-03-11T18:08:09.788Z,1773252489.788 [Waterlinked](INFO): Powering down 2026-03-11T18:08:10.273Z,1773252490.273 [DataOverHttps](INFO): Moved sent file to Logs/20260311T180406/Courier0004.lzma.bak 2026-03-11T18:08:10.274Z,1773252490.274 [DataOverHttps](INFO): SBD MOMSN=26714994 2026-03-11T18:08:16.136Z,1773252496.136 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:08:17.753Z,1773252497.753 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:08:26.467Z,1773252506.467 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260310T222717/Express0626.lzma 2026-03-11T18:08:27.478Z,1773252507.478 [DataOverHttps](INFO): Moved sent file to Logs/20260310T222717/Express0626.lzma.bak 2026-03-11T18:08:27.478Z,1773252507.478 [DataOverHttps](INFO): SBD MOMSN=26714998 2026-03-11T18:08:31.084Z,1773252511.084 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:08:32.720Z,1773252512.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:08:34.353Z,1773252514.353 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-11T18:08:45.489Z,1773252525.489 [DataOverHttps](INFO): Sending 954 bytes from file Logs/20260311T180406/Express0001.lzma 2026-03-11T18:08:46.458Z,1773252526.458 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:08:46.489Z,1773252526.489 [DataOverHttps](INFO): Moved sent file to Logs/20260311T180406/Express0001.lzma.bak 2026-03-11T18:08:46.490Z,1773252526.490 [DataOverHttps](INFO): SBD MOMSN=26715006 2026-03-11T18:08:47.671Z,1773252527.671 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:01.407Z,1773252541.407 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:02.216Z,1773252542.216 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20260311T180406/Express0005.lzma 2026-03-11T18:09:02.621Z,1773252542.621 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:03.217Z,1773252543.217 [DataOverHttps](INFO): Moved sent file to Logs/20260311T180406/Express0005.lzma.bak 2026-03-11T18:09:03.218Z,1773252543.218 [DataOverHttps](INFO): SBD MOMSN=26715025 2026-03-11T18:09:04.661Z,1773252544.661 [Default:CheckIn:Read_Iridium] Stopped 2026-03-11T18:09:04.661Z,1773252544.661 [Default:CheckIn:C.Wait] Running Loop=1 2026-03-11T18:09:04.662Z,1773252544.662 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-03-11T18:09:16.792Z,1773252556.792 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:17.612Z,1773252557.612 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:32.141Z,1773252572.141 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:32.960Z,1773252572.960 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:47.490Z,1773252587.490 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:09:48.712Z,1773252588.712 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:10:02.844Z,1773252602.844 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:10:03.651Z,1773252603.651 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:10:18.598Z,1773252618.598 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-11T18:10:19.016Z,1773252619.016 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-11T18:10:19.023Z,1773252619.023 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 5. 2026-03-11T18:10:19.026Z,1773252619.026 [BPC1](INFO): Received data from all battery sticks. 2026-03-11T18:14:05.273Z,1773252845.273 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-03-11T18:14:05.273Z,1773252845.273 [Default:CheckIn:C.Wait] Stopped 2026-03-11T18:14:05.274Z,1773252845.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-03-11T18:14:05.274Z,1773252845.274 [Default:CheckIn:D] Running Loop=1 2026-03-11T18:14:05.659Z,1773252845.659 [Default:CheckIn:D] Stopped 2026-03-11T18:14:05.659Z,1773252845.659 [Default:CheckIn:E] Running Loop=1 2026-03-11T18:14:06.066Z,1773252846.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.391699 min 2026-03-11T18:14:06.066Z,1773252846.066 [Default:CheckIn:E] Stopped 2026-03-11T18:14:06.067Z,1773252846.067 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-03-11T18:14:06.067Z,1773252846.067 [Default:CheckIn] Stopped 2026-03-11T18:14:06.067Z,1773252846.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-03-11T18:14:06.067Z,1773252846.067 [Default:CheckIn](INFO): Running loop #2 2026-03-11T18:14:06.067Z,1773252846.067 [Default:CheckIn] Running Loop=2 2026-03-11T18:14:06.067Z,1773252846.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-03-11T18:14:06.067Z,1773252846.067 [Default:CheckIn:Read_GPS] Running Loop=1 2026-03-11T18:14:08.077Z,1773252848.077 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181401.00,A,3648.29987,N,12147.63373,W,17.145,258.58,110326,,,D*40 2026-03-11T18:14:08.079Z,1773252848.079 [NAL9602](INFO): GPS fix at 20260311T181401: (36.804998, -121.793896) 2026-03-11T18:14:08.089Z,1773252848.089 [Default:CheckIn:Read_GPS] Stopped 2026-03-11T18:14:08.089Z,1773252848.089 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-03-11T18:14:15.247Z,1773252855.247 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20260311T180406/Courier0007.lzma 2026-03-11T18:14:16.249Z,1773252856.249 [DataOverHttps](INFO): Moved sent file to Logs/20260311T180406/Courier0007.lzma.bak 2026-03-11T18:14:16.250Z,1773252856.250 [DataOverHttps](INFO): SBD MOMSN=26715072 2026-03-11T18:14:31.951Z,1773252871.951 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20260311T180406/Express0008.lzma 2026-03-11T18:14:32.954Z,1773252872.954 [DataOverHttps](INFO): Moved sent file to Logs/20260311T180406/Express0008.lzma.bak 2026-03-11T18:14:32.954Z,1773252872.954 [DataOverHttps](INFO): SBD MOMSN=26715081 2026-03-11T18:14:33.944Z,1773252873.944 [NAL9602](INFO): SBD MO Status=1, MOMSN=13040, MT Status=0, MTMSN=0 2026-03-11T18:14:33.944Z,1773252873.944 [NAL9602](INFO): No messages in MT queue 2026-03-11T18:14:34.756Z,1773252874.756 [Default:CheckIn:Read_Iridium] Stopped 2026-03-11T18:14:34.756Z,1773252874.756 [Default:CheckIn:C.Wait] Running Loop=1 2026-03-11T18:14:34.756Z,1773252874.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-03-11T18:15:04.641Z,1773252904.641 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-11T18:19:35.324Z,1773253175.324 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-03-11T18:19:35.324Z,1773253175.324 [Default:CheckIn:C.Wait] Stopped 2026-03-11T18:19:35.324Z,1773253175.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-03-11T18:19:35.324Z,1773253175.324 [Default:CheckIn:D] Running Loop=1 2026-03-11T18:19:35.739Z,1773253175.739 [Default:CheckIn:D] Stopped 2026-03-11T18:19:35.739Z,1773253175.739 [Default:CheckIn:E] Running Loop=1 2026-03-11T18:19:36.135Z,1773253176.135 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.893039 min 2026-03-11T18:19:36.135Z,1773253176.135 [Default:CheckIn:E] Stopped 2026-03-11T18:19:36.135Z,1773253176.135 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-03-11T18:19:36.135Z,1773253176.135 [Default:CheckIn] Stopped 2026-03-11T18:19:36.135Z,1773253176.135 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-03-11T18:19:36.136Z,1773253176.136 [Default:CheckIn](INFO): Running loop #3 2026-03-11T18:19:36.136Z,1773253176.136 [Default:CheckIn] Running Loop=3 2026-03-11T18:19:36.136Z,1773253176.136 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-03-11T18:19:36.136Z,1773253176.136 [Default:CheckIn:Read_GPS] Running Loop=1 2026-03-11T18:19:38.146Z,1773253178.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181932.00,A,3648.24932,N,12148.36400,W,0.641,270.06,110326,,,D*76 2026-03-11T18:19:38.149Z,1773253178.149 [NAL9602](INFO): GPS fix at 20260311T181932: (36.804155, -121.806067) 2026-03-11T18:19:38.158Z,1773253178.158 [Default:CheckIn:Read_GPS] Stopped 2026-03-11T18:19:38.159Z,1773253178.159 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-03-11T18:19:45.763Z,1773253185.763 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20260311T180406/Courier0010.lzma 2026-03-11T18:19:46.765Z,1773253186.765 [DataOverHttps](INFO): Moved sent file to Logs/20260311T180406/Courier0010.lzma.bak 2026-03-11T18:19:46.766Z,1773253186.766 [DataOverHttps](IMPORTANT): SBD MOMSN=26715104, MTMSN=20260311T181940