2025-04-17T00:18:30.806Z,1744849110.806 [Supervisor](DEBUG): Initializing supervisor. 2025-04-17T00:18:30.810Z,1744849110.810 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-04-17T00:18:30.811Z,1744849110.811 [SyncHandler](INFO): Protected caller Thread ID is 5137 2025-04-17T00:18:30.811Z,1744849110.811 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-04-17T00:18:30.812Z,1744849110.812 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-04-17T00:18:30.812Z,1744849110.812 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5138 2025-04-17T00:18:30.816Z,1744849110.816 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-04-17T00:18:30.833Z,1744849110.833 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-04-17T00:18:30.834Z,1744849110.834 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-04-17T00:18:30.835Z,1744849110.835 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5139 2025-04-17T00:18:30.839Z,1744849110.839 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-04-17T00:18:30.840Z,1744849110.840 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-04-17T00:18:30.840Z,1744849110.840 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5140 2025-04-17T00:18:30.842Z,1744849110.842 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-04-17T00:18:30.843Z,1744849110.843 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-04-17T00:18:30.843Z,1744849110.843 [logger ThreadHandler](INFO): Protected caller Thread ID is 5141 2025-04-17T00:18:30.847Z,1744849110.847 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-04-17T00:18:30.847Z,1744849110.847 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-04-17T00:18:30.851Z,1744849110.851 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-04-17T00:18:31.398Z,1744849111.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-04-17T00:18:31.400Z,1744849111.400 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-04-17T00:18:31.623Z,1744849111.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-04-17T00:18:31.623Z,1744849111.623 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-04-17T00:18:31.717Z,1744849111.717 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-04-17T00:18:31.960Z,1744849111.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-04-17T00:18:31.962Z,1744849111.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-04-17T00:18:32.052Z,1744849112.052 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-04-17T00:18:32.159Z,1744849112.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-04-17T00:18:32.160Z,1744849112.160 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-04-17T00:18:32.520Z,1744849112.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-04-17T00:18:32.522Z,1744849112.522 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-04-17T00:18:32.664Z,1744849112.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-04-17T00:18:32.666Z,1744849112.666 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-04-17T00:18:33.215Z,1744849113.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-04-17T00:18:33.217Z,1744849113.217 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-04-17T00:18:33.409Z,1744849113.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-04-17T00:18:33.411Z,1744849113.411 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-04-17T00:18:33.829Z,1744849113.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-04-17T00:18:33.831Z,1744849113.831 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-04-17T00:18:34.122Z,1744849114.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-04-17T00:18:34.124Z,1744849114.124 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-04-17T00:18:34.684Z,1744849114.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-04-17T00:18:34.688Z,1744849114.688 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-04-17T00:18:35.524Z,1744849115.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-04-17T00:18:35.526Z,1744849115.526 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-04-17T00:18:35.875Z,1744849115.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-04-17T00:18:35.876Z,1744849115.876 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-04-17T00:18:36.119Z,1744849116.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-04-17T00:18:36.121Z,1744849116.121 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2025-04-17T00:18:36.125Z,1744849116.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2025-04-17T00:18:36.254Z,1744849116.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2025-04-17T00:18:36.350Z,1744849116.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2025-04-17T00:18:36.496Z,1744849116.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2025-04-17T00:18:36.594Z,1744849116.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2025-04-17T00:18:36.867Z,1744849116.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-04-17T00:18:36.868Z,1744849116.868 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2025-04-17T00:18:36.983Z,1744849116.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2025-04-17T00:18:37.101Z,1744849117.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2025-04-17T00:18:37.212Z,1744849117.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2025-04-17T00:18:37.371Z,1744849117.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2025-04-17T00:18:37.480Z,1744849117.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2025-04-17T00:18:37.578Z,1744849117.578 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-04-17T00:18:37.598Z,1744849117.598 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-04-17T00:18:37.615Z,1744849117.615 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-04-17T00:18:37.617Z,1744849117.617 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-04-17T00:18:37.778Z,1744849117.778 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-04-17T00:18:37.780Z,1744849117.780 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-04-17T00:18:37.849Z,1744849117.849 [VerticalControl](DEBUG): Construct VerticalControl. 2025-04-17T00:18:37.906Z,1744849117.906 [VerticalControl] Loaded 2025-04-17T00:18:37.907Z,1744849117.907 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-04-17T00:18:37.909Z,1744849117.909 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-04-17T00:18:37.955Z,1744849117.955 [HorizontalControl] Loaded 2025-04-17T00:18:37.955Z,1744849117.955 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-04-17T00:18:37.958Z,1744849117.958 [SpeedControl](DEBUG): Construct SpeedControl. 2025-04-17T00:18:37.961Z,1744849117.961 [SpeedControl] Loaded 2025-04-17T00:18:37.961Z,1744849117.961 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-04-17T00:18:37.963Z,1744849117.963 [LoopControl](DEBUG): Construct LoopControl. 2025-04-17T00:18:37.964Z,1744849117.964 [LoopControl] Loaded 2025-04-17T00:18:37.964Z,1744849117.964 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-04-17T00:18:37.965Z,1744849117.965 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-04-17T00:18:37.966Z,1744849117.966 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-04-17T00:18:38.129Z,1744849118.129 [BuoyancyServo] Loaded 2025-04-17T00:18:38.130Z,1744849118.130 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-04-17T00:18:38.152Z,1744849118.152 [ElevatorServo] Loaded 2025-04-17T00:18:38.152Z,1744849118.152 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-04-17T00:18:38.172Z,1744849118.172 [MassServo] Loaded 2025-04-17T00:18:38.173Z,1744849118.173 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-04-17T00:18:38.193Z,1744849118.193 [RudderServo] Loaded 2025-04-17T00:18:38.193Z,1744849118.193 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-04-17T00:18:38.209Z,1744849118.209 [ThrusterHE] Loaded 2025-04-17T00:18:38.209Z,1744849118.209 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-04-17T00:18:38.210Z,1744849118.210 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-04-17T00:18:38.210Z,1744849118.210 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-04-17T00:18:38.280Z,1744849118.280 [DepthRateCalculator] Loaded 2025-04-17T00:18:38.280Z,1744849118.280 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-04-17T00:18:38.285Z,1744849118.285 [PitchRateCalculator] Loaded 2025-04-17T00:18:38.285Z,1744849118.285 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-04-17T00:18:38.294Z,1744849118.294 [SpeedCalculator] Loaded 2025-04-17T00:18:38.295Z,1744849118.295 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-04-17T00:18:38.299Z,1744849118.299 [YawRateCalculator] Loaded 2025-04-17T00:18:38.299Z,1744849118.299 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-04-17T00:18:38.317Z,1744849118.317 [ElevatorOffsetCalculator] Loaded 2025-04-17T00:18:38.317Z,1744849118.317 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-04-17T00:18:38.318Z,1744849118.318 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-04-17T00:18:38.318Z,1744849118.318 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-04-17T00:18:38.455Z,1744849118.455 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-04-17T00:18:38.455Z,1744849118.455 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-04-17T00:18:38.469Z,1744849118.469 [NavChart] Loaded 2025-04-17T00:18:38.469Z,1744849118.469 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-04-17T00:18:38.475Z,1744849118.475 [UniversalFixResidualReporter] Loaded 2025-04-17T00:18:38.475Z,1744849118.475 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-04-17T00:18:38.475Z,1744849118.475 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-04-17T00:18:38.477Z,1744849118.477 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-04-17T00:18:39.341Z,1744849119.341 [AHRS_M2] Loaded 2025-04-17T00:18:39.342Z,1744849119.342 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-04-17T00:18:39.595Z,1744849119.595 [BackseatComponent] Loaded 2025-04-17T00:18:39.595Z,1744849119.595 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-04-17T00:18:39.596Z,1744849119.596 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0 2025-04-17T00:18:39.596Z,1744849119.596 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5223 2025-04-17T00:18:39.599Z,1744849119.599 [LcmUniversalReporter] Loaded 2025-04-17T00:18:39.600Z,1744849119.600 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-04-17T00:18:39.606Z,1744849119.606 [BioacousticsDataBridge] Loaded 2025-04-17T00:18:39.606Z,1744849119.606 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2025-04-17T00:18:41.011Z,1744849121.011 [BPC1] Loaded 2025-04-17T00:18:41.011Z,1744849121.011 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-04-17T00:18:41.099Z,1744849121.099 [DATMMP] Loaded 2025-04-17T00:18:41.099Z,1744849121.099 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2025-04-17T00:18:41.100Z,1744849121.100 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 4096C4E0 2025-04-17T00:18:41.101Z,1744849121.101 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 5224 2025-04-17T00:18:41.400Z,1744849121.400 [DataOverHttps] Loaded 2025-04-17T00:18:41.401Z,1744849121.401 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-04-17T00:18:41.401Z,1744849121.401 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4099C4E0 2025-04-17T00:18:41.402Z,1744849121.402 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5225 2025-04-17T00:18:41.425Z,1744849121.425 [Depth_Keller] Loaded 2025-04-17T00:18:41.425Z,1744849121.425 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-04-17T00:18:41.489Z,1744849121.489 [NAL9602] Loaded 2025-04-17T00:18:41.489Z,1744849121.489 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-04-17T00:18:41.519Z,1744849121.519 [Onboard] Loaded 2025-04-17T00:18:41.519Z,1744849121.519 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-04-17T00:18:41.520Z,1744849121.520 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409CC4E0 2025-04-17T00:18:41.520Z,1744849121.520 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5226 2025-04-17T00:18:41.535Z,1744849121.535 [Power24vConverter] Loaded 2025-04-17T00:18:41.535Z,1744849121.535 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-04-17T00:18:41.551Z,1744849121.551 [Radio_Surface] Loaded 2025-04-17T00:18:41.551Z,1744849121.551 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-04-17T00:18:41.552Z,1744849121.552 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FC4E0 2025-04-17T00:18:41.552Z,1744849121.552 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5227 2025-04-17T00:18:41.567Z,1744849121.567 [Sonardyne_Nano] Loaded 2025-04-17T00:18:41.568Z,1744849121.568 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-04-17T00:18:41.568Z,1744849121.568 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-04-17T00:18:41.569Z,1744849121.569 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-04-17T00:18:41.668Z,1744849121.668 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-04-17T00:18:41.668Z,1744849121.668 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-04-17T00:18:41.877Z,1744849121.877 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-04-17T00:18:41.878Z,1744849121.878 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-04-17T00:18:41.924Z,1744849121.924 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-04-17T00:18:41.925Z,1744849121.925 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-04-17T00:18:42.149Z,1744849122.149 [CTD_Seabird] Loaded 2025-04-17T00:18:42.149Z,1744849122.149 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-04-17T00:18:42.150Z,1744849122.150 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B404E0 2025-04-17T00:18:42.150Z,1744849122.150 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5228 2025-04-17T00:18:42.172Z,1744849122.172 [PAR_Licor] Loaded 2025-04-17T00:18:42.172Z,1744849122.172 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-04-17T00:18:42.216Z,1744849122.216 [WetLabsBB2FL] Loaded 2025-04-17T00:18:42.216Z,1744849122.216 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-04-17T00:18:42.217Z,1744849122.217 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B704E0 2025-04-17T00:18:42.218Z,1744849122.218 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5229 2025-04-17T00:18:42.219Z,1744849122.219 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-04-17T00:18:42.219Z,1744849122.219 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-04-17T00:18:42.609Z,1744849122.609 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-04-17T00:18:42.611Z,1744849122.611 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-04-17T00:18:42.778Z,1744849122.778 [SBIT](DEBUG): Construct Startup Built In Test. 2025-04-17T00:18:42.786Z,1744849122.786 [SBIT] Loaded 2025-04-17T00:18:42.787Z,1744849122.787 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-04-17T00:18:42.789Z,1744849122.789 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-04-17T00:18:42.802Z,1744849122.802 [IBIT] Loaded 2025-04-17T00:18:42.802Z,1744849122.802 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-04-17T00:18:42.808Z,1744849122.808 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-04-17T00:18:42.911Z,1744849122.911 [CBIT] Loaded 2025-04-17T00:18:42.911Z,1744849122.911 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-04-17T00:18:42.912Z,1744849122.912 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-04-17T00:18:42.918Z,1744849122.918 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-04-17T00:18:42.921Z,1744849122.921 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-04-17T00:18:42.932Z,1744849122.932 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-04-17T00:18:42.933Z,1744849122.933 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6C4E0 2025-04-17T00:18:42.933Z,1744849122.933 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5230 2025-04-17T00:18:42.938Z,1744849122.938 [Supervisor](INFO): Main Thread ID is 5136 2025-04-17T00:18:42.938Z,1744849122.938 [Supervisor](DEBUG): Running supervisor. 2025-04-17T00:18:42.939Z,1744849122.939 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5231 2025-04-17T00:18:42.939Z,1744849122.939 [CommandExec](INFO): Initializing the command executive. 2025-04-17T00:18:42.941Z,1744849122.941 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5232 2025-04-17T00:18:42.943Z,1744849122.943 [controlThread ThreadHandler](INFO): Handler Thread ID is 5233 2025-04-17T00:18:42.943Z,1744849122.943 [controlThread](DEBUG): Initializing ControlThread 2025-04-17T00:18:42.944Z,1744849122.944 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-04-17T00:18:42.945Z,1744849122.945 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-04-17T00:18:42.946Z,1744849122.946 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-04-17T00:18:42.947Z,1744849122.947 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-04-17T00:18:42.948Z,1744849122.948 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-04-17T00:18:42.948Z,1744849122.948 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-04-17T00:18:42.949Z,1744849122.949 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-04-17T00:18:42.949Z,1744849122.949 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-04-17T00:18:42.949Z,1744849122.949 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-04-17T00:18:42.951Z,1744849122.951 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-04-17T00:18:42.951Z,1744849122.951 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-04-17T00:18:42.959Z,1744849122.959 [SBIT](INFO): Initialize SBIT Component. 2025-04-17T00:18:42.959Z,1744849122.959 [SBIT](IMPORTANT): git: 2024-12-16_A-129-gdfc4b4cb1 2025-04-17T00:18:42.959Z,1744849122.959 [SBIT](INFO): git hash: dfc4b4cb1f964e05e7aa12596fa9f50e33a9d75d 2025-04-17T00:18:42.960Z,1744849122.960 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-04-17T00:18:42.961Z,1744849122.961 [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 2025-04-17T00:18:42.962Z,1744849122.962 [SBIT](INFO): Beginning SBIT in 120.000000 seconds. 2025-04-17T00:18:42.962Z,1744849122.962 [IBIT](INFO): Initialize IBIT Component. 2025-04-17T00:18:42.963Z,1744849122.963 [CBIT](DEBUG): Initialize CBIT Component. 2025-04-17T00:18:42.964Z,1744849122.964 [logger ThreadHandler](INFO): Handler Thread ID is 5234 2025-04-17T00:18:42.974Z,1744849122.974 [CBIT](DEBUG): Initialized mux pins. 2025-04-17T00:18:42.974Z,1744849122.974 [CBIT](DEBUG): Initializing the watchdog timer. 2025-04-17T00:18:42.983Z,1744849122.983 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5235 2025-04-17T00:18:42.995Z,1744849122.995 [DATMMP ThreadHandler](INFO): Handler Thread ID is 5236 2025-04-17T00:18:42.995Z,1744849122.995 [DATMMP](INFO): Start 2025-04-17T00:18:42.995Z,1744849122.995 [DATMMP](INFO): Powering up 2025-04-17T00:18:42.996Z,1744849122.996 [DATMMP](DEBUG): Initializing DATMMP. 2025-04-17T00:18:42.998Z,1744849122.998 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-04-17T00:18:42.999Z,1744849122.999 [CBIT](DEBUG): Initializing heartbeat. 2025-04-17T00:18:43.001Z,1744849123.001 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5238 2025-04-17T00:18:43.002Z,1744849123.002 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-04-17T00:18:43.011Z,1744849123.011 [Onboard ThreadHandler](INFO): Handler Thread ID is 5239 2025-04-17T00:18:43.041Z,1744849123.041 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5240 2025-04-17T00:18:43.051Z,1744849123.051 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5241 2025-04-17T00:18:43.051Z,1744849123.051 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-04-17T00:18:43.056Z,1744849123.056 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5243 2025-04-17T00:18:43.059Z,1744849123.059 [WetLabsBB2FL](INFO): Powering up 2025-04-17T00:18:43.060Z,1744849123.060 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5245 2025-04-17T00:18:43.068Z,1744849123.068 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000 2025-04-17T00:18:43.068Z,1744849123.068 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000 2025-04-17T00:18:43.069Z,1744849123.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000 2025-04-17T00:18:43.069Z,1744849123.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000 2025-04-17T00:18:43.069Z,1744849123.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000 2025-04-17T00:18:43.069Z,1744849123.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000 2025-04-17T00:18:43.069Z,1744849123.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000 2025-04-17T00:18:43.069Z,1744849123.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000 2025-04-17T00:18:43.070Z,1744849123.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000 2025-04-17T00:18:43.070Z,1744849123.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000 2025-04-17T00:18:43.070Z,1744849123.070 [CBIT](DEBUG): Deactivating GF circuits. 2025-04-17T00:18:43.070Z,1744849123.070 [CBIT](DEBUG): Deactivating emergency mode. 2025-04-17T00:18:43.071Z,1744849123.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000 2025-04-17T00:18:43.071Z,1744849123.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000 2025-04-17T00:18:43.071Z,1744849123.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000 2025-04-17T00:18:43.071Z,1744849123.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000 2025-04-17T00:18:43.071Z,1744849123.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000 2025-04-17T00:18:43.072Z,1744849123.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000 2025-04-17T00:18:43.072Z,1744849123.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000 2025-04-17T00:18:43.072Z,1744849123.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000 2025-04-17T00:18:43.072Z,1744849123.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000 2025-04-17T00:18:43.072Z,1744849123.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000 2025-04-17T00:18:43.110Z,1744849123.110 [CBIT](DEBUG): Backplane powered. 2025-04-17T00:18:43.111Z,1744849123.111 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-04-17T00:18:43.112Z,1744849123.112 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-04-17T00:18:43.112Z,1744849123.112 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-04-17T00:18:43.117Z,1744849123.117 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-04-17T00:18:43.160Z,1744849123.160 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-04-17T00:18:43.182Z,1744849123.182 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-17T00:18:43.202Z,1744849123.202 [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 } } } 2025-04-17T00:18:43.218Z,1744849123.218 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-04-17T00:18:43.219Z,1744849123.219 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-04-17T00:18:43.227Z,1744849123.227 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-04-17T00:18:43.246Z,1744849123.246 [DATMMP](INFO): Starting 2025-04-17T00:18:43.442Z,1744849123.442 [Radio_Surface](INFO): Powering up 2025-04-17T00:18:43.498Z,1744849123.498 [DATMMP](INFO): Starting 2025-04-17T00:18:43.518Z,1744849123.518 [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 2025-04-17T00:18:43.576Z,1744849123.576 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-04-17T00:18:43.577Z,1744849123.577 [Default:A.Wait](DEBUG): Construct Wait. 2025-04-17T00:18:43.584Z,1744849123.584 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-17T00:18:43.612Z,1744849123.612 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-17T00:18:43.639Z,1744849123.639 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-17T00:18:43.645Z,1744849123.645 [Default:E.Execute](DEBUG): Construct Execute. 2025-04-17T00:18:43.664Z,1744849123.664 [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" } } 2025-04-17T00:18:43.669Z,1744849123.669 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-17T00:18:43.691Z,1744849123.691 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-04-17T00:18:43.750Z,1744849123.750 [DATMMP](INFO): Starting 2025-04-17T00:18:43.811Z,1744849123.811 [Power24vConverter](INFO): Powering up. 2025-04-17T00:18:43.812Z,1744849123.812 [Sonardyne_Nano](INFO): Initializing. 2025-04-17T00:18:43.896Z,1744849123.896 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:18:43.902Z,1744849123.902 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-17T00:18:43.904Z,1744849123.904 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:18:43.911Z,1744849123.911 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-17T00:18:43.911Z,1744849123.911 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:18:43.918Z,1744849123.918 [MassServo](DEBUG): Initializing MassServo. 2025-04-17T00:18:43.919Z,1744849123.919 [RudderServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:18:43.926Z,1744849123.926 [RudderServo](DEBUG): Initializing RudderServo. 2025-04-17T00:18:43.927Z,1744849123.927 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-17T00:18:43.935Z,1744849123.935 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-17T00:18:44.015Z,1744849124.015 [DATMMP](INFO): Starting 2025-04-17T00:18:44.266Z,1744849124.266 [DATMMP](INFO): Starting 2025-04-17T00:18:44.518Z,1744849124.518 [DATMMP](INFO): Starting 2025-04-17T00:18:44.770Z,1744849124.770 [DATMMP](INFO): Starting 2025-04-17T00:18:45.023Z,1744849125.023 [DATMMP](INFO): Starting 2025-04-17T00:18:45.274Z,1744849125.274 [DATMMP](INFO): Starting 2025-04-17T00:18:45.526Z,1744849125.526 [DATMMP](INFO): Starting 2025-04-17T00:18:45.670Z,1744849125.670 [WetLabsBB2FL](INFO): Powering down 2025-04-17T00:18:45.778Z,1744849125.778 [DATMMP](INFO): Starting 2025-04-17T00:18:46.030Z,1744849126.030 [DATMMP](INFO): Starting 2025-04-17T00:18:46.101Z,1744849126.101 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-04-17T00:18:46.282Z,1744849126.282 [DATMMP](INFO): Starting 2025-04-17T00:18:46.534Z,1744849126.534 [DATMMP](INFO): Starting 2025-04-17T00:18:46.786Z,1744849126.786 [DATMMP](INFO): Starting 2025-04-17T00:18:47.039Z,1744849127.039 [DATMMP](INFO): Starting 2025-04-17T00:18:47.290Z,1744849127.290 [DATMMP](INFO): Starting 2025-04-17T00:18:47.339Z,1744849127.339 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:18:47.543Z,1744849127.543 [DATMMP](INFO): Starting 2025-04-17T00:18:47.794Z,1744849127.794 [DATMMP](INFO): Starting 2025-04-17T00:18:48.046Z,1744849128.046 [DATMMP](INFO): Starting 2025-04-17T00:18:48.299Z,1744849128.299 [DATMMP](INFO): Starting 2025-04-17T00:18:48.550Z,1744849128.550 [DATMMP](INFO): Starting 2025-04-17T00:18:48.802Z,1744849128.802 [DATMMP](INFO): Starting 2025-04-17T00:18:49.055Z,1744849129.055 [DATMMP](INFO): Starting 2025-04-17T00:18:49.306Z,1744849129.306 [DATMMP](INFO): Starting 2025-04-17T00:18:49.540Z,1744849129.540 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-04-17T00:18:49.559Z,1744849129.559 [DATMMP](INFO): Starting 2025-04-17T00:18:49.812Z,1744849129.812 [DATMMP](INFO): Starting 2025-04-17T00:18:50.062Z,1744849130.062 [DATMMP](INFO): Starting 2025-04-17T00:18:50.314Z,1744849130.314 [DATMMP](INFO): Starting 2025-04-17T00:18:50.568Z,1744849130.568 [DATMMP](INFO): Starting 2025-04-17T00:18:50.818Z,1744849130.818 [DATMMP](INFO): Starting 2025-04-17T00:18:51.071Z,1744849131.071 [DATMMP](INFO): Starting 2025-04-17T00:18:51.322Z,1744849131.322 [DATMMP](INFO): Starting 2025-04-17T00:18:51.575Z,1744849131.575 [DATMMP](INFO): Starting 2025-04-17T00:18:51.826Z,1744849131.826 [DATMMP](INFO): Starting 2025-04-17T00:18:52.078Z,1744849132.078 [DATMMP](INFO): Starting 2025-04-17T00:18:52.330Z,1744849132.330 [DATMMP](INFO): Starting 2025-04-17T00:18:52.582Z,1744849132.582 [DATMMP](INFO): Starting 2025-04-17T00:18:52.834Z,1744849132.834 [DATMMP](INFO): Starting 2025-04-17T00:18:53.086Z,1744849133.086 [DATMMP](INFO): Starting 2025-04-17T00:18:53.338Z,1744849133.338 [DATMMP](INFO): Starting 2025-04-17T00:18:53.590Z,1744849133.590 [DATMMP](INFO): Starting 2025-04-17T00:18:53.842Z,1744849133.842 [DATMMP](INFO): Starting 2025-04-17T00:18:54.094Z,1744849134.094 [DATMMP](INFO): Starting 2025-04-17T00:18:54.346Z,1744849134.346 [DATMMP](INFO): Starting 2025-04-17T00:18:54.598Z,1744849134.598 [DATMMP](INFO): Starting 2025-04-17T00:18:54.850Z,1744849134.850 [DATMMP](INFO): Starting 2025-04-17T00:18:55.103Z,1744849135.103 [DATMMP](INFO): Starting 2025-04-17T00:18:55.355Z,1744849135.355 [DATMMP](INFO): Starting 2025-04-17T00:18:55.607Z,1744849135.607 [DATMMP](INFO): Starting 2025-04-17T00:18:55.858Z,1744849135.858 [DATMMP](INFO): Starting 2025-04-17T00:18:56.111Z,1744849136.111 [DATMMP](INFO): Starting 2025-04-17T00:18:56.111Z,1744849136.111 [DATMMP](INFO): DAT read: 2025-04-17T00:18:56.112Z,1744849136.112 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-04-17T00:18:56.362Z,1744849136.362 [DATMMP](INFO): Starting 2025-04-17T00:18:56.614Z,1744849136.614 [DATMMP](INFO): Starting 2025-04-17T00:18:56.866Z,1744849136.866 [DATMMP](INFO): Starting 2025-04-17T00:18:57.118Z,1744849137.118 [DATMMP](INFO): Starting 2025-04-17T00:18:57.370Z,1744849137.370 [DATMMP](INFO): Starting 2025-04-17T00:18:57.622Z,1744849137.622 [DATMMP](INFO): Starting 2025-04-17T00:18:57.874Z,1744849137.874 [DATMMP](INFO): Starting 2025-04-17T00:18:57.875Z,1744849137.875 [DATMMP](INFO): DAT read: MF Frequency Band 2025-04-17T00:18:57.876Z,1744849137.876 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-04-17T00:18:57.877Z,1744849137.877 [DATMMP](INFO): DAT read: Mar 16 2000 23:30:29 2025-04-17T00:18:58.126Z,1744849138.126 [DATMMP](INFO): Starting 2025-04-17T00:18:58.378Z,1744849138.378 [DATMMP](INFO): Starting 2025-04-17T00:18:58.630Z,1744849138.630 [DATMMP](INFO): Starting 2025-04-17T00:18:58.883Z,1744849138.883 [DATMMP](INFO): Starting 2025-04-17T00:18:58.883Z,1744849138.883 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2025-04-17T00:18:58.885Z,1744849138.885 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-17T00:18:58.885Z,1744849138.885 [DATMMP](INFO): commRate: 800 2025-04-17T00:18:59.134Z,1744849139.134 [DATMMP](INFO): Starting 2025-04-17T00:18:59.386Z,1744849139.386 [DATMMP](INFO): Starting 2025-04-17T00:18:59.638Z,1744849139.638 [DATMMP](INFO): Starting 2025-04-17T00:18:59.890Z,1744849139.890 [DATMMP](INFO): Starting 2025-04-17T00:19:00.144Z,1744849140.144 [DATMMP](INFO): Starting 2025-04-17T00:19:00.394Z,1744849140.394 [DATMMP](INFO): Starting 2025-04-17T00:19:00.646Z,1744849140.646 [DATMMP](INFO): Starting 2025-04-17T00:19:00.898Z,1744849140.898 [DATMMP](INFO): Starting 2025-04-17T00:19:00.908Z,1744849140.908 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004283 2025-04-17T00:19:00.950Z,1744849140.950 [DATMMP](INFO): entering command mode 2025-04-17T00:19:01.151Z,1744849141.151 [DATMMP](INFO): Starting 2025-04-17T00:19:01.151Z,1744849141.151 [DATMMP](INFO): DAT read: 2025-04-17T00:19:01.151Z,1744849141.151 [DATMMP](INFO): DAT read: user:1> 2025-04-17T00:19:01.152Z,1744849141.152 [DATMMP](DEBUG): read user prompt 1: user:1> 2025-04-17T00:19:01.152Z,1744849141.152 [DATMMP](INFO): entering MMP mode 2025-04-17T00:19:01.403Z,1744849141.403 [DATMMP](INFO): Starting 2025-04-17T00:19:01.403Z,1744849141.403 [DATMMP](INFO): GSXN notify for xid: 0 2025-04-17T00:19:01.405Z,1744849141.405 [DATMMP](INFO): status rx: x1 2025-04-17T00:19:01.405Z,1744849141.405 [DATMMP](INFO): MMP status: 1: started 2025-04-17T00:19:01.405Z,1744849141.405 [DATMMP](INFO): Received message type: status 2025-04-17T00:19:01.405Z,1744849141.405 [DATMMP](INFO): Handled 2025-04-17T00:19:01.654Z,1744849141.654 [DATMMP](INFO): Starting 2025-04-17T00:19:01.655Z,1744849141.655 [DATMMP](INFO): Sent Tx Power Config 8 2025-04-17T00:19:01.656Z,1744849141.656 [DATMMP](INFO): Setting local address: 12 2025-04-17T00:19:01.658Z,1744849141.658 [DATMMP](INFO): Setting time to: 0:19:1 And date to:4/17/2025 2025-04-17T00:19:01.907Z,1744849141.907 [DATMMP](INFO): Starting 2025-04-17T00:19:01.907Z,1744849141.907 [DATMMP](INFO): GSXN notify for xid: 0 2025-04-17T00:19:01.908Z,1744849141.908 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2025-04-17T00:19:01.908Z,1744849141.908 [DATMMP](INFO): Received message type: mdmpower 2025-04-17T00:19:01.908Z,1744849141.908 [DATMMP](INFO): Handled 2025-04-17T00:19:02.159Z,1744849142.159 [DATMMP](INFO): Starting 2025-04-17T00:19:02.159Z,1744849142.159 [DATMMP](INFO): GSXN notify for xid: 10 2025-04-17T00:19:02.160Z,1744849142.160 [DATMMP](INFO): sreg_txpower rx: x8 2025-04-17T00:19:02.160Z,1744849142.160 [DATMMP](INFO): Received message type: sreg_txpower 2025-04-17T00:19:02.160Z,1744849142.160 [DATMMP](INFO): Match for : sreg_txpower 2025-04-17T00:19:02.160Z,1744849142.160 [DATMMP](INFO): ACK 2025-04-17T00:19:02.410Z,1744849142.410 [DATMMP](INFO): Starting 2025-04-17T00:19:02.411Z,1744849142.411 [DATMMP](INFO): GSXN notify for xid: 0 2025-04-17T00:19:02.412Z,1744849142.412 [DATMMP](INFO): mdmlocal rx: xC x0 x0 x0 2025-04-17T00:19:02.412Z,1744849142.412 [DATMMP](INFO): Received message type: mdmlocal 2025-04-17T00:19:02.412Z,1744849142.412 [DATMMP](INFO): Handled 2025-04-17T00:19:02.662Z,1744849142.662 [DATMMP](INFO): Starting 2025-04-17T00:19:02.663Z,1744849142.663 [DATMMP](INFO): GSXN notify for xid: 11 2025-04-17T00:19:02.663Z,1744849142.663 [DATMMP](INFO): sreg_locaddr rx: xC 2025-04-17T00:19:02.664Z,1744849142.664 [DATMMP](INFO): Received message type: sreg_locaddr 2025-04-17T00:19:02.664Z,1744849142.664 [DATMMP](INFO): Match for : sreg_locaddr 2025-04-17T00:19:02.664Z,1744849142.664 [DATMMP](INFO): ACK 2025-04-17T00:19:02.914Z,1744849142.914 [DATMMP](INFO): Starting 2025-04-17T00:19:02.915Z,1744849142.915 [DATMMP](INFO): GSXN notify for xid: 12 2025-04-17T00:19:02.916Z,1744849142.916 [DATMMP](INFO): timedate rx: x0 x0 x13 x1 x3 x11 x7D x0 2025-04-17T00:19:02.916Z,1744849142.916 [DATMMP](INFO): Received message type: timedate 2025-04-17T00:19:02.916Z,1744849142.916 [DATMMP](INFO): Match for : timedate 2025-04-17T00:19:02.916Z,1744849142.916 [DATMMP](INFO): ACK 2025-04-17T00:19:03.166Z,1744849143.166 [DATMMP](INFO): Starting 2025-04-17T00:19:03.167Z,1744849143.167 [DATMMP](INFO): GSXN notify for xid: 0 2025-04-17T00:19:03.168Z,1744849143.168 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2025-04-17T00:19:03.168Z,1744849143.168 [DATMMP](INFO): Received message type: sys_verb 2025-04-17T00:19:03.168Z,1744849143.168 [DATMMP](INFO): Handled 2025-04-17T00:19:03.419Z,1744849143.419 [DATMMP](INFO): Starting 2025-04-17T00:19:03.419Z,1744849143.419 [DATMMP](INFO): GSXN notify for xid: 13 2025-04-17T00:19:03.420Z,1744849143.420 [DATMMP](INFO): sreg_verbosity rx: x0 2025-04-17T00:19:03.420Z,1744849143.420 [DATMMP](INFO): Received message type: sreg_verbosity 2025-04-17T00:19:03.420Z,1744849143.420 [DATMMP](INFO): Match for : sreg_verbosity 2025-04-17T00:19:03.420Z,1744849143.420 [DATMMP](INFO): ACK 2025-04-17T00:19:03.670Z,1744849143.670 [DATMMP](INFO): Starting 2025-04-17T00:19:03.671Z,1744849143.671 [DATMMP](INFO): GSXN notify for xid: 14 2025-04-17T00:19:03.672Z,1744849143.672 [DATMMP](INFO): version rx: x2 x5 2025-04-17T00:19:03.672Z,1744849143.672 [DATMMP](INFO): DatMMPVersion: 2.5 2025-04-17T00:19:03.672Z,1744849143.672 [DATMMP](INFO): Received message type: version 2025-04-17T00:19:03.672Z,1744849143.672 [DATMMP](INFO): Match for : version 2025-04-17T00:19:03.672Z,1744849143.672 [DATMMP](INFO): ACK 2025-04-17T00:19:03.672Z,1744849143.672 [DATMMP](INFO): in MMP mode: config complete going to runnable 2025-04-17T00:19:11.560Z,1744849151.560 [NAL9602](INFO): Powering up NAL9602 2025-04-17T00:19:18.287Z,1744849158.287 [DATMMP](INFO): GSXN notify for xid: 0 2025-04-17T00:19:18.288Z,1744849158.288 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2025-04-17T00:19:18.288Z,1744849158.288 [DATMMP](INFO): Received message type: timesync 2025-04-17T00:19:18.288Z,1744849158.288 [DATMMP](INFO): Handled 2025-04-17T00:19:22.472Z,1744849162.472 [NAL9602](INFO): NAL9602 initialized 2025-04-17T00:19:24.191Z,1744849164.191 [MassServo](FAULT): Command Overflow Error 2025-04-17T00:19:24.191Z,1744849164.191 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: no error 2025-04-17T00:19:24.191Z,1744849164.191 [MassServo] Hardware Fault, FailCount= 1 2025-04-17T00:19:24.191Z,1744849164.191 [MassServo](ERROR): Hardware Fault 2025-04-17T00:19:24.239Z,1744849164.239 [CBIT](ERROR): Hardware Fault in component: MassServo 2025-04-17T00:19:24.597Z,1744849164.597 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-17T00:19:24.597Z,1744849164.597 [MassServo](INFO): Powering down 2025-04-17T00:19:24.937Z,1744849164.937 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-04-17T00:19:25.389Z,1744849165.389 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:19:25.390Z,1744849165.390 [MassServo](DEBUG): Initializing MassServo. 2025-04-17T00:19:25.431Z,1744849165.431 [CBIT](INFO): Clearing failed state for component MassServo 2025-04-17T00:19:25.431Z,1744849165.431 [MassServo] No Fault, FailCount= 1 2025-04-17T00:19:26.167Z,1744849166.167 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:26.948Z,1744849166.948 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:27.391Z,1744849167.391 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:27.799Z,1744849167.799 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2025-04-17T00:19:27.799Z,1744849167.799 [MassServo] Communications Fault, FailCount= 2 2025-04-17T00:19:27.799Z,1744849167.799 [MassServo](ERROR): Communications Fault 2025-04-17T00:19:27.803Z,1744849167.803 [CBIT](ERROR): Communications Fault in component: MassServo 2025-04-17T00:19:27.844Z,1744849167.844 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:27.901Z,1744849167.901 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-17T00:19:27.901Z,1744849167.901 [MassServo](INFO): Powering down 2025-04-17T00:19:28.132Z,1744849168.132 [DATMMP](INFO): GSXN notify for xid: 0 2025-04-17T00:19:28.133Z,1744849168.133 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2025-04-17T00:19:28.133Z,1744849168.133 [DATMMP](INFO): Received message type: timesync 2025-04-17T00:19:28.134Z,1744849168.134 [DATMMP](INFO): Handled 2025-04-17T00:19:28.258Z,1744849168.258 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:28.647Z,1744849168.647 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:28.880Z,1744849168.880 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:19:28.881Z,1744849168.881 [MassServo](DEBUG): Initializing MassServo. 2025-04-17T00:19:28.927Z,1744849168.927 [CBIT](INFO): Clearing failed state for component MassServo 2025-04-17T00:19:28.927Z,1744849168.927 [MassServo] No Fault, FailCount= 2 2025-04-17T00:19:29.044Z,1744849169.044 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:29.501Z,1744849169.501 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:29.923Z,1744849169.923 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:30.338Z,1744849170.338 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:30.745Z,1744849170.745 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:31.142Z,1744849171.142 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:31.510Z,1744849171.510 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2025-04-17T00:19:31.511Z,1744849171.511 [MassServo] Communications Fault, FailCount= 3 2025-04-17T00:19:31.511Z,1744849171.511 [MassServo](ERROR): Communications Fault 2025-04-17T00:19:31.515Z,1744849171.515 [CBIT](ERROR): Communications Fault in component: MassServo 2025-04-17T00:19:31.570Z,1744849171.570 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:31.661Z,1744849171.661 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-17T00:19:31.661Z,1744849171.661 [MassServo](INFO): Powering down 2025-04-17T00:19:31.807Z,1744849171.807 [CommandExec](IMPORTANT): got command quit 2025-04-17T00:19:31.954Z,1744849171.954 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:32.347Z,1744849172.347 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:32.399Z,1744849172.399 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:19:32.399Z,1744849172.399 [MassServo](DEBUG): Initializing MassServo. 2025-04-17T00:19:32.432Z,1744849172.432 [CBIT](INFO): Clearing failed state for component MassServo 2025-04-17T00:19:32.432Z,1744849172.432 [MassServo] No Fault, FailCount= 3 2025-04-17T00:19:32.755Z,1744849172.755 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:32.810Z,1744849172.810 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-04-17T00:19:32.815Z,1744849172.815 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:32.817Z,1744849172.817 [CommandExec](INFO): Uninitializing the command executive. 2025-04-17T00:19:32.817Z,1744849172.817 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-17T00:19:32.817Z,1744849172.817 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:32.887Z,1744849172.887 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-17T00:19:32.887Z,1744849172.887 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-17T00:19:32.887Z,1744849172.887 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:32.888Z,1744849172.888 [NavChartDb](INFO): Join timeout helper Thread ID is 5371 2025-04-17T00:19:33.159Z,1744849173.159 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:33.199Z,1744849173.199 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:33.199Z,1744849173.199 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:33.212Z,1744849173.212 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-17T00:19:33.212Z,1744849173.212 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:33.214Z,1744849173.214 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5372 2025-04-17T00:19:33.455Z,1744849173.455 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:33.455Z,1744849173.455 [WetLabsBB2FL](INFO): Powering down 2025-04-17T00:19:33.456Z,1744849173.456 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:33.475Z,1744849173.475 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-17T00:19:33.475Z,1744849173.475 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:33.476Z,1744849173.476 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5373 2025-04-17T00:19:33.564Z,1744849173.564 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:34.003Z,1744849174.003 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:34.255Z,1744849174.255 [CTD_Seabird](INFO): Powering down 2025-04-17T00:19:34.266Z,1744849174.266 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:34.267Z,1744849174.267 [CTD_Seabird](INFO): Powering down 2025-04-17T00:19:34.279Z,1744849174.279 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:34.298Z,1744849174.298 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-17T00:19:34.299Z,1744849174.299 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:34.299Z,1744849174.299 [Radio_Surface](INFO): Join timeout helper Thread ID is 5374 2025-04-17T00:19:34.374Z,1744849174.374 [Radio_Surface](INFO): Powering down 2025-04-17T00:19:34.376Z,1744849174.376 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:34.376Z,1744849174.376 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:34.378Z,1744849174.378 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-17T00:19:34.378Z,1744849174.378 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:34.379Z,1744849174.379 [Onboard](INFO): Join timeout helper Thread ID is 5375 2025-04-17T00:19:34.415Z,1744849174.415 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:34.810Z,1744849174.810 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2025-04-17T00:19:34.811Z,1744849174.811 [MassServo] Communications Fault, FailCount= 4 2025-04-17T00:19:34.811Z,1744849174.811 [MassServo](ERROR): Communications Fault 2025-04-17T00:19:34.814Z,1744849174.814 [CBIT](ERROR): Communications Fault in component: MassServo 2025-04-17T00:19:34.877Z,1744849174.877 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:34.929Z,1744849174.929 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-17T00:19:34.929Z,1744849174.929 [MassServo](INFO): Powering down 2025-04-17T00:19:35.235Z,1744849175.235 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:35.654Z,1744849175.654 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:35.744Z,1744849175.744 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:19:35.745Z,1744849175.745 [MassServo](DEBUG): Initializing MassServo. 2025-04-17T00:19:35.766Z,1744849175.766 [CBIT](INFO): Clearing failed state for component MassServo 2025-04-17T00:19:35.766Z,1744849175.766 [MassServo] No Fault, FailCount= 4 2025-04-17T00:19:36.058Z,1744849176.058 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:36.470Z,1744849176.470 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:36.869Z,1744849176.869 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:37.255Z,1744849177.255 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:37.659Z,1744849177.659 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:38.055Z,1744849178.055 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:38.055Z,1744849178.055 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:38.059Z,1744849178.059 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-17T00:19:38.059Z,1744849178.059 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:38.060Z,1744849178.060 [DataOverHttps](INFO): Join timeout helper Thread ID is 5376 2025-04-17T00:19:38.060Z,1744849178.060 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2025-04-17T00:19:38.060Z,1744849178.060 [MassServo] Communications Fault, FailCount= 5 2025-04-17T00:19:38.060Z,1744849178.060 [MassServo](ERROR): Communications Fault 2025-04-17T00:19:38.081Z,1744849178.081 [CBIT](ERROR): Communications Fault in component: MassServo 2025-04-17T00:19:38.081Z,1744849178.081 [CBIT](CRITICAL): Communications Fault in component: MassServo 2025-04-17T00:19:38.221Z,1744849178.221 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:38.292Z,1744849178.292 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-17T00:19:38.292Z,1744849178.292 [MassServo](INFO): Powering down 2025-04-17T00:19:38.352Z,1744849178.352 [CBIT](INFO): Critical error at 20250417T001938 2025-04-17T00:19:38.512Z,1744849178.512 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:38.907Z,1744849178.907 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:38.982Z,1744849178.982 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-17T00:19:38.982Z,1744849178.982 [MassServo](DEBUG): Initializing MassServo. 2025-04-17T00:19:39.311Z,1744849179.311 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:39.696Z,1744849179.696 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:39.728Z,1744849179.728 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:39.755Z,1744849179.755 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:39.759Z,1744849179.759 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2025-04-17T00:19:39.759Z,1744849179.759 [DATMMP ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:39.760Z,1744849179.760 [DATMMP](INFO): Join timeout helper Thread ID is 5377 2025-04-17T00:19:39.975Z,1744849179.975 [DATMMP](INFO): Stop 2025-04-17T00:19:39.975Z,1744849179.975 [DATMMP](INFO): uninitialize 2025-04-17T00:19:39.975Z,1744849179.975 [DATMMP](INFO): Powering down 2025-04-17T00:19:40.047Z,1744849180.047 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:40.048Z,1744849180.048 [DATMMP](INFO): uninitialize 2025-04-17T00:19:40.048Z,1744849180.048 [DATMMP](INFO): Powering down 2025-04-17T00:19:40.049Z,1744849180.049 [DATMMP ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.054Z,1744849180.054 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-04-17T00:19:40.055Z,1744849180.055 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.055Z,1744849180.055 [BackseatComponent](INFO): Join timeout helper Thread ID is 5378 2025-04-17T00:19:40.127Z,1744849180.127 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-04-17T00:19:40.155Z,1744849180.155 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:40.155Z,1744849180.155 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.156Z,1744849180.156 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-17T00:19:40.156Z,1744849180.156 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.157Z,1744849180.157 [logger](INFO): Join timeout helper Thread ID is 5379 2025-04-17T00:19:40.188Z,1744849180.188 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:40.188Z,1744849180.188 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.203Z,1744849180.203 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-17T00:19:40.203Z,1744849180.203 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.204Z,1744849180.204 [CommandLine](INFO): Join timeout helper Thread ID is 5380 2025-04-17T00:19:40.214Z,1744849180.214 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:40.215Z,1744849180.215 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.223Z,1744849180.223 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-17T00:19:40.223Z,1744849180.223 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.224Z,1744849180.224 [CommandExec](INFO): Join timeout helper Thread ID is 5381 2025-04-17T00:19:40.239Z,1744849180.239 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-17T00:19:40.239Z,1744849180.239 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.240Z,1744849180.240 [controlThread](INFO): Join timeout helper Thread ID is 5382 2025-04-17T00:19:40.507Z,1744849180.507 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-17T00:19:40.507Z,1744849180.507 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-17T00:19:40.507Z,1744849180.507 [AHRS_M2](INFO): Powering down 2025-04-17T00:19:40.509Z,1744849180.509 [NAL9602](INFO): Powering down 2025-04-17T00:19:40.510Z,1744849180.510 [Sonardyne_Nano](INFO): Powering down 2025-04-17T00:19:40.511Z,1744849180.511 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-17T00:19:40.512Z,1744849180.512 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-17T00:19:40.512Z,1744849180.512 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-17T00:19:40.512Z,1744849180.512 [MissionManager](INFO): Uninitializing Mission Default 2025-04-17T00:19:40.515Z,1744849180.515 [MissionManager](INFO): Uninitializing Mission Startup 2025-04-17T00:19:40.516Z,1744849180.516 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-17T00:19:40.517Z,1744849180.517 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-17T00:19:40.517Z,1744849180.517 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-17T00:19:40.517Z,1744849180.517 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-17T00:19:40.517Z,1744849180.517 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-17T00:19:40.517Z,1744849180.517 [BuoyancyServo](INFO): Powering down 2025-04-17T00:19:40.531Z,1744849180.531 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-17T00:19:40.531Z,1744849180.531 [ElevatorServo](INFO): Powering down 2025-04-17T00:19:40.532Z,1744849180.532 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-17T00:19:40.532Z,1744849180.532 [MassServo](INFO): Powering down 2025-04-17T00:19:40.532Z,1744849180.532 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-17T00:19:40.533Z,1744849180.533 [RudderServo](INFO): Powering down 2025-04-17T00:19:40.533Z,1744849180.533 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-17T00:19:40.533Z,1744849180.533 [ThrusterHE](INFO): Powering down 2025-04-17T00:19:40.534Z,1744849180.534 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-17T00:19:40.535Z,1744849180.535 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-17T00:19:40.535Z,1744849180.535 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-17T00:19:40.535Z,1744849180.535 [CBIT](DEBUG): Powering off loads. 2025-04-17T00:19:40.547Z,1744849180.547 [CBIT](DEBUG): Disabling WDT. 2025-04-17T00:19:40.558Z,1744849180.558 [CBIT](DEBUG): Opening all GF detection circuits. 2025-04-17T00:19:40.559Z,1744849180.559 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.697Z,1744849180.697 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.700Z,1744849180.700 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.719Z,1744849180.719 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.764Z,1744849180.764 [DATMMP ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:40.996Z,1744849180.996 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:41.168Z,1744849181.168 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:41.185Z,1744849181.185 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:41.485Z,1744849181.485 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-17T00:19:41.982Z,1744849181.982 [logger ThreadHandler](INFO): Thread cancelled.