2025-03-25T21:26:02.202Z,1742937962.202 [Supervisor](DEBUG): Initializing supervisor. 2025-03-25T21:26:02.207Z,1742937962.207 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-25T21:26:02.207Z,1742937962.207 [SyncHandler](INFO): Protected caller Thread ID is 2328 2025-03-25T21:26:02.208Z,1742937962.208 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-25T21:26:02.209Z,1742937962.209 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-25T21:26:02.209Z,1742937962.209 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2329 2025-03-25T21:26:02.214Z,1742937962.214 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-25T21:26:02.234Z,1742937962.234 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-25T21:26:02.235Z,1742937962.235 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-25T21:26:02.235Z,1742937962.235 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2330 2025-03-25T21:26:02.240Z,1742937962.240 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-25T21:26:02.241Z,1742937962.241 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-25T21:26:02.241Z,1742937962.241 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2331 2025-03-25T21:26:02.243Z,1742937962.243 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-25T21:26:02.244Z,1742937962.244 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-25T21:26:02.245Z,1742937962.245 [logger ThreadHandler](INFO): Protected caller Thread ID is 2332 2025-03-25T21:26:02.248Z,1742937962.248 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-25T21:26:02.249Z,1742937962.249 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-25T21:26:02.250Z,1742937962.250 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-25T21:26:02.474Z,1742937962.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-25T21:26:02.474Z,1742937962.474 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-25T21:26:02.593Z,1742937962.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-25T21:26:02.593Z,1742937962.593 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-25T21:26:02.700Z,1742937962.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-25T21:26:02.701Z,1742937962.701 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-25T21:26:03.203Z,1742937963.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-25T21:26:03.204Z,1742937963.204 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-25T21:26:03.565Z,1742937963.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-25T21:26:03.566Z,1742937963.566 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-25T21:26:03.923Z,1742937963.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-25T21:26:03.924Z,1742937963.924 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-25T21:26:04.125Z,1742937964.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-25T21:26:04.126Z,1742937964.126 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-25T21:26:04.217Z,1742937964.217 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-25T21:26:04.309Z,1742937964.309 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-25T21:26:04.734Z,1742937964.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-25T21:26:04.735Z,1742937964.735 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-25T21:26:05.041Z,1742937965.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-25T21:26:05.041Z,1742937965.041 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-25T21:26:05.608Z,1742937965.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-25T21:26:05.609Z,1742937965.609 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-25T21:26:05.980Z,1742937965.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-25T21:26:05.981Z,1742937965.981 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-25T21:26:06.326Z,1742937966.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-25T21:26:06.326Z,1742937966.326 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-25T21:26:06.635Z,1742937966.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-25T21:26:06.635Z,1742937966.635 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-25T21:26:07.776Z,1742937967.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-25T21:26:07.778Z,1742937967.778 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2025-03-25T21:26:07.779Z,1742937967.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2025-03-25T21:26:07.875Z,1742937967.875 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2025-03-25T21:26:08.019Z,1742937968.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2025-03-25T21:26:08.299Z,1742937968.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-25T21:26:08.300Z,1742937968.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2025-03-25T21:26:08.436Z,1742937968.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2025-03-25T21:26:08.544Z,1742937968.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2025-03-25T21:26:08.649Z,1742937968.649 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2025-03-25T21:26:08.768Z,1742937968.768 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2025-03-25T21:26:08.864Z,1742937968.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2025-03-25T21:26:08.962Z,1742937968.962 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2025-03-25T21:26:09.065Z,1742937969.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2025-03-25T21:26:09.245Z,1742937969.245 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2025-03-25T21:26:09.245Z,1742937969.245 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-03-25T21:26:09.259Z,1742937969.259 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-25T21:26:09.311Z,1742937969.311 [DepthRateCalculator] Loaded 2025-03-25T21:26:09.312Z,1742937969.312 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-25T21:26:09.317Z,1742937969.317 [PitchRateCalculator] Loaded 2025-03-25T21:26:09.317Z,1742937969.317 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-25T21:26:09.328Z,1742937969.328 [SpeedCalculator] Loaded 2025-03-25T21:26:09.328Z,1742937969.328 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-25T21:26:09.333Z,1742937969.333 [YawRateCalculator] Loaded 2025-03-25T21:26:09.333Z,1742937969.333 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-25T21:26:09.355Z,1742937969.355 [ElevatorOffsetCalculator] Loaded 2025-03-25T21:26:09.355Z,1742937969.355 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-25T21:26:09.356Z,1742937969.356 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-25T21:26:09.356Z,1742937969.356 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-25T21:26:09.386Z,1742937969.386 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-25T21:26:09.386Z,1742937969.386 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-25T21:26:09.397Z,1742937969.397 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-25T21:26:09.397Z,1742937969.397 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-25T21:26:09.925Z,1742937969.925 [AHRS_M2] Loaded 2025-03-25T21:26:09.926Z,1742937969.926 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-25T21:26:09.964Z,1742937969.964 [BackseatComponent] Loaded 2025-03-25T21:26:09.964Z,1742937969.964 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-03-25T21:26:09.965Z,1742937969.965 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0 2025-03-25T21:26:09.966Z,1742937969.966 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2414 2025-03-25T21:26:09.969Z,1742937969.969 [LcmUniversalReporter] Loaded 2025-03-25T21:26:09.969Z,1742937969.969 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-03-25T21:26:10.761Z,1742937970.761 [BPC1] Loaded 2025-03-25T21:26:10.761Z,1742937970.761 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-25T21:26:10.856Z,1742937970.856 [DAT] Loaded 2025-03-25T21:26:10.856Z,1742937970.856 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-03-25T21:26:10.857Z,1742937970.857 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0 2025-03-25T21:26:10.858Z,1742937970.858 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2415 2025-03-25T21:26:10.933Z,1742937970.933 [DataOverHttps] Loaded 2025-03-25T21:26:10.933Z,1742937970.933 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-25T21:26:10.934Z,1742937970.934 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0 2025-03-25T21:26:10.935Z,1742937970.935 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2416 2025-03-25T21:26:10.957Z,1742937970.957 [Depth_Keller] Loaded 2025-03-25T21:26:10.958Z,1742937970.958 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-25T21:26:10.963Z,1742937970.963 [DropWeight] Loaded 2025-03-25T21:26:10.963Z,1742937970.963 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-25T21:26:10.984Z,1742937970.984 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7 2025-03-25T21:26:10.984Z,1742937970.984 [MultiRay] Loaded 2025-03-25T21:26:10.984Z,1742937970.984 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2025-03-25T21:26:11.048Z,1742937971.048 [NAL9602] Loaded 2025-03-25T21:26:11.049Z,1742937971.049 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-25T21:26:11.078Z,1742937971.078 [Onboard] Loaded 2025-03-25T21:26:11.078Z,1742937971.078 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-25T21:26:11.079Z,1742937971.079 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0 2025-03-25T21:26:11.080Z,1742937971.080 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2417 2025-03-25T21:26:11.086Z,1742937971.086 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2025-03-25T21:26:11.099Z,1742937971.099 [PowerOnly] Loaded 2025-03-25T21:26:11.099Z,1742937971.099 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2025-03-25T21:26:11.113Z,1742937971.113 [Power24vConverter] Loaded 2025-03-25T21:26:11.113Z,1742937971.113 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-25T21:26:11.130Z,1742937971.130 [Radio_Surface] Loaded 2025-03-25T21:26:11.130Z,1742937971.130 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-25T21:26:11.131Z,1742937971.131 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0 2025-03-25T21:26:11.132Z,1742937971.132 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2418 2025-03-25T21:26:11.156Z,1742937971.156 [Waterlinked] Loaded 2025-03-25T21:26:11.157Z,1742937971.157 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-03-25T21:26:11.157Z,1742937971.157 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-25T21:26:11.158Z,1742937971.158 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-25T21:26:11.551Z,1742937971.551 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-25T21:26:11.552Z,1742937971.552 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-25T21:26:11.578Z,1742937971.578 [NavChart] Loaded 2025-03-25T21:26:11.578Z,1742937971.578 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-25T21:26:11.584Z,1742937971.584 [UniversalFixResidualReporter] Loaded 2025-03-25T21:26:11.584Z,1742937971.584 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-25T21:26:11.584Z,1742937971.584 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-25T21:26:11.585Z,1742937971.585 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-25T21:26:11.755Z,1742937971.755 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-25T21:26:11.769Z,1742937971.769 [SBIT] Loaded 2025-03-25T21:26:11.769Z,1742937971.769 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-25T21:26:11.772Z,1742937971.772 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-25T21:26:11.785Z,1742937971.785 [IBIT] Loaded 2025-03-25T21:26:11.785Z,1742937971.785 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-25T21:26:11.790Z,1742937971.790 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-25T21:26:11.871Z,1742937971.871 [CBIT] Loaded 2025-03-25T21:26:11.871Z,1742937971.871 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-25T21:26:11.902Z,1742937971.902 [GFScanner] Loaded 2025-03-25T21:26:11.903Z,1742937971.903 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-25T21:26:11.903Z,1742937971.903 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-25T21:26:11.904Z,1742937971.904 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-25T21:26:12.078Z,1742937972.078 [CTD_Seabird] Loaded 2025-03-25T21:26:12.078Z,1742937972.078 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-25T21:26:12.080Z,1742937972.080 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0 2025-03-25T21:26:12.080Z,1742937972.080 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2419 2025-03-25T21:26:12.102Z,1742937972.102 [PAR_Licor] Loaded 2025-03-25T21:26:12.103Z,1742937972.103 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-25T21:26:12.148Z,1742937972.148 [WetLabsBB2FL] Loaded 2025-03-25T21:26:12.148Z,1742937972.148 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-03-25T21:26:12.149Z,1742937972.149 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0 2025-03-25T21:26:12.150Z,1742937972.150 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2420 2025-03-25T21:26:12.150Z,1742937972.150 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-25T21:26:12.151Z,1742937972.151 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-25T21:26:12.292Z,1742937972.292 [BuoyancyServo] Loaded 2025-03-25T21:26:12.292Z,1742937972.292 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-25T21:26:12.316Z,1742937972.316 [ElevatorServo] Loaded 2025-03-25T21:26:12.316Z,1742937972.316 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-25T21:26:12.340Z,1742937972.340 [MassServo] Loaded 2025-03-25T21:26:12.340Z,1742937972.340 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-25T21:26:12.362Z,1742937972.362 [RudderServo] Loaded 2025-03-25T21:26:12.362Z,1742937972.362 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-25T21:26:12.381Z,1742937972.381 [ThrusterHE] Loaded 2025-03-25T21:26:12.381Z,1742937972.381 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-25T21:26:12.381Z,1742937972.381 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-25T21:26:12.382Z,1742937972.382 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-25T21:26:12.437Z,1742937972.437 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-25T21:26:12.489Z,1742937972.489 [VerticalControl] Loaded 2025-03-25T21:26:12.490Z,1742937972.490 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-25T21:26:12.493Z,1742937972.493 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-25T21:26:12.537Z,1742937972.537 [HorizontalControl] Loaded 2025-03-25T21:26:12.537Z,1742937972.537 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-25T21:26:12.540Z,1742937972.540 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-25T21:26:12.541Z,1742937972.541 [SpeedControl] Loaded 2025-03-25T21:26:12.542Z,1742937972.542 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-25T21:26:12.545Z,1742937972.545 [LoopControl](DEBUG): Construct LoopControl. 2025-03-25T21:26:12.545Z,1742937972.545 [LoopControl] Loaded 2025-03-25T21:26:12.545Z,1742937972.545 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-25T21:26:12.546Z,1742937972.546 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-25T21:26:12.546Z,1742937972.546 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-25T21:26:12.681Z,1742937972.681 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-25T21:26:12.682Z,1742937972.682 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-25T21:26:12.879Z,1742937972.879 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-25T21:26:12.880Z,1742937972.880 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-25T21:26:13.400Z,1742937973.400 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-25T21:26:13.401Z,1742937973.401 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-25T21:26:13.460Z,1742937973.460 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-25T21:26:13.466Z,1742937973.466 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-25T21:26:13.469Z,1742937973.469 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-25T21:26:13.481Z,1742937973.481 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-25T21:26:13.482Z,1742937973.482 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-03-25T21:26:13.482Z,1742937973.482 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2421 2025-03-25T21:26:13.487Z,1742937973.487 [Supervisor](INFO): Main Thread ID is 833 2025-03-25T21:26:13.487Z,1742937973.487 [Supervisor](DEBUG): Running supervisor. 2025-03-25T21:26:13.488Z,1742937973.488 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2422 2025-03-25T21:26:13.488Z,1742937973.488 [CommandExec](INFO): Initializing the command executive. 2025-03-25T21:26:13.490Z,1742937973.490 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2423 2025-03-25T21:26:13.492Z,1742937973.492 [controlThread ThreadHandler](INFO): Handler Thread ID is 2424 2025-03-25T21:26:13.492Z,1742937973.492 [controlThread](DEBUG): Initializing ControlThread 2025-03-25T21:26:13.493Z,1742937973.493 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-25T21:26:13.493Z,1742937973.493 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-25T21:26:13.494Z,1742937973.494 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-25T21:26:13.494Z,1742937973.494 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-25T21:26:13.494Z,1742937973.494 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-25T21:26:13.499Z,1742937973.499 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-25T21:26:13.499Z,1742937973.499 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-25T21:26:13.500Z,1742937973.500 [SBIT](INFO): Initialize SBIT Component. 2025-03-25T21:26:13.500Z,1742937973.500 [SBIT](IMPORTANT): git: 2025-03-12_C-17-gf91acea95 2025-03-25T21:26:13.500Z,1742937973.500 [SBIT](INFO): git hash: f91acea956ccd3087fd37a250748aa93a9f51542 2025-03-25T21:26:13.501Z,1742937973.501 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-25T21:26:13.502Z,1742937973.502 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-03-25T21:26:13.503Z,1742937973.503 [SBIT](INFO): Beginning SBIT in 47.000000 seconds. 2025-03-25T21:26:13.504Z,1742937973.504 [IBIT](INFO): Initialize IBIT Component. 2025-03-25T21:26:13.504Z,1742937973.504 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-25T21:26:13.505Z,1742937973.505 [logger ThreadHandler](INFO): Handler Thread ID is 2425 2025-03-25T21:26:13.515Z,1742937973.515 [CBIT](DEBUG): Initialized mux pins. 2025-03-25T21:26:13.515Z,1742937973.515 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-25T21:26:13.524Z,1742937973.524 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2426 2025-03-25T21:26:13.539Z,1742937973.539 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-25T21:26:13.540Z,1742937973.540 [CBIT](DEBUG): Initializing heartbeat. 2025-03-25T21:26:13.540Z,1742937973.540 [DAT ThreadHandler](INFO): Handler Thread ID is 2427 2025-03-25T21:26:13.541Z,1742937973.541 [DAT](INFO): Powering up 2025-03-25T21:26:13.541Z,1742937973.541 [DAT](DEBUG): Initializing DAT. 2025-03-25T21:26:13.545Z,1742937973.545 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2429 2025-03-25T21:26:13.546Z,1742937973.546 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-25T21:26:13.568Z,1742937973.568 [Onboard ThreadHandler](INFO): Handler Thread ID is 2430 2025-03-25T21:26:13.585Z,1742937973.585 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2431 2025-03-25T21:26:13.608Z,1742937973.608 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2432 2025-03-25T21:26:13.608Z,1742937973.608 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-25T21:26:13.611Z,1742937973.611 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-25T21:26:13.613Z,1742937973.613 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2434 2025-03-25T21:26:13.616Z,1742937973.616 [WetLabsBB2FL](INFO): Powering up 2025-03-25T21:26:13.617Z,1742937973.617 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2436 2025-03-25T21:26:13.620Z,1742937973.620 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-25T21:26:13.620Z,1742937973.620 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-25T21:26:13.620Z,1742937973.620 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-25T21:26:13.620Z,1742937973.620 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-25T21:26:13.620Z,1742937973.620 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-25T21:26:13.620Z,1742937973.620 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-25T21:26:13.621Z,1742937973.621 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-25T21:26:13.621Z,1742937973.621 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-25T21:26:13.651Z,1742937973.651 [CBIT](DEBUG): Backplane powered. 2025-03-25T21:26:13.652Z,1742937973.652 [GFScanner](DEBUG): Initializing GFScanner 2025-03-25T21:26:13.652Z,1742937973.652 [GFScanner](DEBUG): Deactivating GF circuits. 2025-03-25T21:26:13.661Z,1742937973.661 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-25T21:26:13.662Z,1742937973.662 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-25T21:26:13.663Z,1742937973.663 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-25T21:26:13.664Z,1742937973.664 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-25T21:26:13.664Z,1742937973.664 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-25T21:26:13.665Z,1742937973.665 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-25T21:26:13.666Z,1742937973.666 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-25T21:26:13.667Z,1742937973.667 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-25T21:26:13.722Z,1742937973.722 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-25T21:26:13.742Z,1742937973.742 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-25T21:26:13.769Z,1742937973.769 [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-03-25T21:26:13.770Z,1742937973.770 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-25T21:26:13.770Z,1742937973.770 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-25T21:26:13.771Z,1742937973.771 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-25T21:26:13.990Z,1742937973.990 [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-03-25T21:26:13.992Z,1742937973.992 [Radio_Surface](INFO): Powering up 2025-03-25T21:26:14.008Z,1742937974.008 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-25T21:26:14.010Z,1742937974.010 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-25T21:26:14.013Z,1742937974.013 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-25T21:26:14.049Z,1742937974.049 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-25T21:26:14.051Z,1742937974.051 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-25T21:26:14.069Z,1742937974.069 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-25T21:26:14.072Z,1742937974.072 [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-03-25T21:26:14.077Z,1742937974.077 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,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,LcmUniversalReporter,Reporter,LogSplitter, 2025-03-25T21:26:14.088Z,1742937974.088 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-25T21:26:14.140Z,1742937974.140 [Depth_Keller](INFO): Initializing. 2025-03-25T21:26:14.144Z,1742937974.144 [MultiRay](INFO): Powering up MultiRay Lights 2025-03-25T21:26:14.144Z,1742937974.144 [MultiRay](INFO): Powering up secondary power supply. 2025-03-25T21:26:14.177Z,1742937974.177 [Power24vConverter](INFO): Powering up. 2025-03-25T21:26:14.190Z,1742937974.190 [Depth_Keller](INFO): Starting 2025-03-25T21:26:14.197Z,1742937974.197 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-03-25T21:26:14.215Z,1742937974.215 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-25T21:26:14.219Z,1742937974.219 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-25T21:26:14.220Z,1742937974.220 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-25T21:26:14.228Z,1742937974.228 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-25T21:26:14.229Z,1742937974.229 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-25T21:26:14.248Z,1742937974.248 [MassServo](DEBUG): Initializing MassServo. 2025-03-25T21:26:14.249Z,1742937974.249 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-25T21:26:14.256Z,1742937974.256 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-25T21:26:14.256Z,1742937974.256 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-25T21:26:14.264Z,1742937974.264 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-25T21:26:14.527Z,1742937974.527 [Depth_Keller](INFO): READING 2025-03-25T21:26:14.578Z,1742937974.578 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:14.578Z,1742937974.578 [Depth_Keller](INFO): READING 2025-03-25T21:26:14.890Z,1742937974.890 [Depth_Keller](INFO): READING 2025-03-25T21:26:14.900Z,1742937974.900 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:14.900Z,1742937974.900 [Depth_Keller](INFO): READING 2025-03-25T21:26:15.294Z,1742937975.294 [Depth_Keller](INFO): READING 2025-03-25T21:26:15.552Z,1742937975.552 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:15.552Z,1742937975.552 [Depth_Keller](INFO): READING 2025-03-25T21:26:15.565Z,1742937975.565 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:15.771Z,1742937975.771 [Depth_Keller](INFO): READING 2025-03-25T21:26:15.779Z,1742937975.779 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:15.779Z,1742937975.779 [Depth_Keller](INFO): READING 2025-03-25T21:26:16.174Z,1742937976.174 [Depth_Keller](INFO): READING 2025-03-25T21:26:16.183Z,1742937976.183 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:16.184Z,1742937976.184 [Depth_Keller](INFO): READING 2025-03-25T21:26:16.236Z,1742937976.236 [WetLabsBB2FL](INFO): Powering down 2025-03-25T21:26:16.578Z,1742937976.578 [Depth_Keller](INFO): READING 2025-03-25T21:26:16.580Z,1742937976.580 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2025-03-25T21:26:16.587Z,1742937976.587 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:16.588Z,1742937976.588 [Depth_Keller](INFO): READING 2025-03-25T21:26:16.985Z,1742937976.985 [Depth_Keller](INFO): READING 2025-03-25T21:26:17.003Z,1742937977.003 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:17.004Z,1742937977.004 [Depth_Keller](INFO): READING 2025-03-25T21:26:17.456Z,1742937977.456 [Depth_Keller](INFO): READING 2025-03-25T21:26:17.466Z,1742937977.466 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:17.466Z,1742937977.466 [Depth_Keller](INFO): READING 2025-03-25T21:26:17.828Z,1742937977.828 [Depth_Keller](INFO): READING 2025-03-25T21:26:17.838Z,1742937977.838 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:17.838Z,1742937977.838 [Depth_Keller](INFO): READING 2025-03-25T21:26:18.221Z,1742937978.221 [Depth_Keller](INFO): READING 2025-03-25T21:26:18.230Z,1742937978.230 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:18.230Z,1742937978.230 [Depth_Keller](INFO): READING 2025-03-25T21:26:18.696Z,1742937978.696 [Depth_Keller](INFO): READING 2025-03-25T21:26:18.705Z,1742937978.705 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:18.706Z,1742937978.706 [Depth_Keller](INFO): READING 2025-03-25T21:26:19.048Z,1742937979.048 [Depth_Keller](INFO): READING 2025-03-25T21:26:19.052Z,1742937979.052 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2025-03-25T21:26:19.080Z,1742937979.080 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:19.080Z,1742937979.080 [Depth_Keller](INFO): READING 2025-03-25T21:26:19.444Z,1742937979.444 [Depth_Keller](INFO): READING 2025-03-25T21:26:19.463Z,1742937979.463 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:19.464Z,1742937979.464 [Depth_Keller](INFO): READING 2025-03-25T21:26:19.712Z,1742937979.712 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-03-25T21:26:19.848Z,1742937979.848 [Depth_Keller](INFO): READING 2025-03-25T21:26:19.858Z,1742937979.858 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:19.859Z,1742937979.859 [Depth_Keller](INFO): READING 2025-03-25T21:26:20.408Z,1742937980.408 [Depth_Keller](INFO): READING 2025-03-25T21:26:20.418Z,1742937980.418 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:20.418Z,1742937980.418 [Depth_Keller](INFO): READING 2025-03-25T21:26:20.652Z,1742937980.652 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-03-25T21:26:20.812Z,1742937980.812 [Depth_Keller](INFO): READING 2025-03-25T21:26:20.831Z,1742937980.831 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:20.832Z,1742937980.832 [Depth_Keller](INFO): READING 2025-03-25T21:26:21.216Z,1742937981.216 [Depth_Keller](INFO): READING 2025-03-25T21:26:21.224Z,1742937981.224 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:21.225Z,1742937981.225 [Depth_Keller](INFO): READING 2025-03-25T21:26:21.620Z,1742937981.620 [Depth_Keller](INFO): READING 2025-03-25T21:26:21.643Z,1742937981.643 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:21.643Z,1742937981.643 [Depth_Keller](INFO): READING 2025-03-25T21:26:22.024Z,1742937982.024 [Depth_Keller](INFO): READING 2025-03-25T21:26:22.032Z,1742937982.032 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:22.033Z,1742937982.033 [Depth_Keller](INFO): READING 2025-03-25T21:26:22.428Z,1742937982.428 [Depth_Keller](INFO): READING 2025-03-25T21:26:22.432Z,1742937982.432 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2025-03-25T21:26:22.451Z,1742937982.451 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:22.451Z,1742937982.451 [Depth_Keller](INFO): READING 2025-03-25T21:26:22.824Z,1742937982.824 [Depth_Keller](INFO): READING 2025-03-25T21:26:22.836Z,1742937982.836 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:22.837Z,1742937982.837 [Depth_Keller](INFO): READING 2025-03-25T21:26:25.132Z,1742937985.132 [DAT](INFO): DAT read: 2025-03-25T21:26:25.133Z,1742937985.133 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-03-25T21:26:26.100Z,1742937986.100 [Depth_Keller](INFO): READING 2025-03-25T21:26:26.109Z,1742937986.109 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:26.109Z,1742937986.109 [Depth_Keller](INFO): READING 2025-03-25T21:26:26.499Z,1742937986.499 [Depth_Keller](INFO): READING 2025-03-25T21:26:26.500Z,1742937986.500 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:26.645Z,1742937986.645 [DAT](INFO): DAT read: MF Frequency Band 2025-03-25T21:26:26.646Z,1742937986.646 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-03-25T21:26:26.646Z,1742937986.646 [DAT](INFO): DAT read: Mar 25 2025 21:26:22 2025-03-25T21:26:26.771Z,1742937986.771 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:26.771Z,1742937986.771 [Depth_Keller](INFO): READING 2025-03-25T21:26:26.885Z,1742937986.885 [Depth_Keller](INFO): READING 2025-03-25T21:26:26.900Z,1742937986.900 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:26.900Z,1742937986.900 [Depth_Keller](INFO): READING 2025-03-25T21:26:27.290Z,1742937987.290 [Depth_Keller](INFO): READING 2025-03-25T21:26:27.363Z,1742937987.363 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:27.364Z,1742937987.364 [Depth_Keller](INFO): READING 2025-03-25T21:26:27.653Z,1742937987.653 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-03-25T21:26:27.654Z,1742937987.654 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-03-25T21:26:27.654Z,1742937987.654 [DAT](INFO): commRate: 800 2025-03-25T21:26:27.693Z,1742937987.693 [Depth_Keller](INFO): READING 2025-03-25T21:26:27.704Z,1742937987.704 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:27.705Z,1742937987.705 [Depth_Keller](INFO): READING 2025-03-25T21:26:28.097Z,1742937988.097 [Depth_Keller](INFO): READING 2025-03-25T21:26:28.100Z,1742937988.100 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2025-03-25T21:26:28.119Z,1742937988.119 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:28.120Z,1742937988.120 [Depth_Keller](INFO): READING 2025-03-25T21:26:28.501Z,1742937988.501 [Depth_Keller](INFO): READING 2025-03-25T21:26:28.582Z,1742937988.582 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:28.592Z,1742937988.592 [Depth_Keller](INFO): READING 2025-03-25T21:26:28.608Z,1742937988.608 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:28.906Z,1742937988.906 [Depth_Keller](INFO): READING 2025-03-25T21:26:28.928Z,1742937988.928 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:28.929Z,1742937988.929 [Depth_Keller](INFO): READING 2025-03-25T21:26:29.310Z,1742937989.310 [Depth_Keller](INFO): READING 2025-03-25T21:26:29.317Z,1742937989.317 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:29.317Z,1742937989.317 [Depth_Keller](INFO): READING 2025-03-25T21:26:29.714Z,1742937989.714 [Depth_Keller](INFO): READING 2025-03-25T21:26:29.719Z,1742937989.719 [DAT](INFO): entering command mode 2025-03-25T21:26:29.726Z,1742937989.726 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:29.726Z,1742937989.726 [Depth_Keller](INFO): READING 2025-03-25T21:26:29.920Z,1742937989.920 [DAT](INFO): DAT read: 2025-03-25T21:26:29.921Z,1742937989.921 [DAT](INFO): DAT read: user:1> 2025-03-25T21:26:29.921Z,1742937989.921 [DAT](INFO): setting verbose to 3 2025-03-25T21:26:30.117Z,1742937990.117 [Depth_Keller](INFO): READING 2025-03-25T21:26:30.138Z,1742937990.138 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:30.138Z,1742937990.138 [Depth_Keller](INFO): READING 2025-03-25T21:26:30.172Z,1742937990.172 [DAT](INFO): DAT read: user:1> 2025-03-25T21:26:30.173Z,1742937990.173 [DAT](INFO): DAT read: Verbose | 3 2025-03-25T21:26:30.173Z,1742937990.173 [DAT](INFO): set verbose to 3 2025-03-25T21:26:30.174Z,1742937990.174 [DAT](INFO): setting DatVerbose to 27440 2025-03-25T21:26:30.424Z,1742937990.424 [DAT](INFO): DAT read: user:2> 2025-03-25T21:26:30.425Z,1742937990.425 [DAT](INFO): DAT read: DatVerbose | 27440 2025-03-25T21:26:30.425Z,1742937990.425 [DAT](INFO): set DatVerbose to 27440 2025-03-25T21:26:30.426Z,1742937990.426 [DAT](INFO): setting transmit power to 8 2025-03-25T21:26:30.521Z,1742937990.521 [Depth_Keller](INFO): READING 2025-03-25T21:26:30.530Z,1742937990.530 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:30.530Z,1742937990.530 [Depth_Keller](INFO): READING 2025-03-25T21:26:30.676Z,1742937990.676 [DAT](INFO): DAT read: user:3> 2025-03-25T21:26:30.677Z,1742937990.677 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-03-25T21:26:30.677Z,1742937990.677 [DAT](INFO): set transmit power to 8 2025-03-25T21:26:30.678Z,1742937990.678 [DAT](INFO): setting local address to 9 2025-03-25T21:26:30.926Z,1742937990.926 [Depth_Keller](INFO): READING 2025-03-25T21:26:30.928Z,1742937990.928 [DAT](INFO): DAT read: user:4> 2025-03-25T21:26:30.929Z,1742937990.929 [DAT](INFO): DAT read: LocalAddr | 9 2025-03-25T21:26:30.929Z,1742937990.929 [DAT](INFO): set local address to 9 2025-03-25T21:26:30.930Z,1742937990.930 [DAT](INFO): Setting time to: 21:26:30 And date to:3/25/2025 2025-03-25T21:26:30.946Z,1742937990.946 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:30.946Z,1742937990.946 [Depth_Keller](INFO): READING 2025-03-25T21:26:31.180Z,1742937991.180 [DAT](INFO): DAT read: user:5> 2025-03-25T21:26:31.181Z,1742937991.181 [DAT](INFO): DAT read: Tue Mar 25, 2025 21:26:30 2025-03-25T21:26:31.181Z,1742937991.181 [DAT](INFO): Local DAT time set to Tue Mar 25, 2025 21:26:30 2025-03-25T21:26:31.330Z,1742937991.330 [Depth_Keller](INFO): READING 2025-03-25T21:26:31.347Z,1742937991.347 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:31.347Z,1742937991.347 [Depth_Keller](INFO): READING 2025-03-25T21:26:31.733Z,1742937991.733 [Depth_Keller](INFO): READING 2025-03-25T21:26:31.752Z,1742937991.752 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:31.753Z,1742937991.753 [Depth_Keller](INFO): READING 2025-03-25T21:26:32.138Z,1742937992.138 [Depth_Keller](INFO): READING 2025-03-25T21:26:32.144Z,1742937992.144 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:32.149Z,1742937992.149 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:32.149Z,1742937992.149 [Depth_Keller](INFO): READING 2025-03-25T21:26:32.542Z,1742937992.542 [Depth_Keller](INFO): READING 2025-03-25T21:26:32.548Z,1742937992.548 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:32.553Z,1742937992.553 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:32.553Z,1742937992.553 [Depth_Keller](INFO): READING 2025-03-25T21:26:32.946Z,1742937992.946 [Depth_Keller](INFO): READING 2025-03-25T21:26:32.957Z,1742937992.957 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:32.958Z,1742937992.958 [Depth_Keller](INFO): READING 2025-03-25T21:26:33.352Z,1742937993.352 [Depth_Keller](INFO): READING 2025-03-25T21:26:33.365Z,1742937993.365 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:33.365Z,1742937993.365 [Depth_Keller](INFO): READING 2025-03-25T21:26:33.754Z,1742937993.754 [Depth_Keller](INFO): READING 2025-03-25T21:26:33.760Z,1742937993.760 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:33.786Z,1742937993.786 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:33.786Z,1742937993.786 [Depth_Keller](INFO): READING 2025-03-25T21:26:34.158Z,1742937994.158 [Depth_Keller](INFO): READING 2025-03-25T21:26:34.169Z,1742937994.169 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:34.169Z,1742937994.169 [Depth_Keller](INFO): READING 2025-03-25T21:26:34.562Z,1742937994.562 [Depth_Keller](INFO): READING 2025-03-25T21:26:34.569Z,1742937994.569 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:34.570Z,1742937994.570 [Depth_Keller](INFO): READING 2025-03-25T21:26:34.968Z,1742937994.968 [Depth_Keller](INFO): READING 2025-03-25T21:26:34.973Z,1742937994.973 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2025-03-25T21:26:34.978Z,1742937994.978 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:34.978Z,1742937994.978 [Depth_Keller](INFO): READING 2025-03-25T21:26:35.369Z,1742937995.369 [Depth_Keller](INFO): READING 2025-03-25T21:26:35.393Z,1742937995.393 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:35.393Z,1742937995.393 [Depth_Keller](INFO): READING 2025-03-25T21:26:35.774Z,1742937995.774 [Depth_Keller](INFO): READING 2025-03-25T21:26:35.780Z,1742937995.780 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2025-03-25T21:26:35.785Z,1742937995.785 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:35.785Z,1742937995.785 [Depth_Keller](INFO): READING 2025-03-25T21:26:36.178Z,1742937996.178 [Depth_Keller](INFO): READING 2025-03-25T21:26:36.189Z,1742937996.189 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:36.189Z,1742937996.189 [Depth_Keller](INFO): READING 2025-03-25T21:26:36.582Z,1742937996.582 [Depth_Keller](INFO): READING 2025-03-25T21:26:36.594Z,1742937996.594 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:36.594Z,1742937996.594 [Depth_Keller](INFO): READING 2025-03-25T21:26:36.987Z,1742937996.987 [Depth_Keller](INFO): READING 2025-03-25T21:26:37.002Z,1742937997.002 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:37.008Z,1742937997.008 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:37.009Z,1742937997.009 [Depth_Keller](INFO): READING 2025-03-25T21:26:37.389Z,1742937997.389 [Depth_Keller](INFO): READING 2025-03-25T21:26:37.396Z,1742937997.396 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:37.401Z,1742937997.401 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:37.401Z,1742937997.401 [Depth_Keller](INFO): READING 2025-03-25T21:26:37.793Z,1742937997.793 [Depth_Keller](INFO): READING 2025-03-25T21:26:37.800Z,1742937997.800 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2025-03-25T21:26:37.806Z,1742937997.806 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:37.806Z,1742937997.806 [Depth_Keller](INFO): READING 2025-03-25T21:26:38.198Z,1742937998.197 [Depth_Keller](INFO): READING 2025-03-25T21:26:38.207Z,1742937998.207 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:38.208Z,1742937998.208 [Depth_Keller](INFO): READING 2025-03-25T21:26:38.617Z,1742937998.617 [Depth_Keller](INFO): READING 2025-03-25T21:26:38.629Z,1742937998.629 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:38.629Z,1742937998.629 [Depth_Keller](INFO): READING 2025-03-25T21:26:39.006Z,1742937999.006 [Depth_Keller](INFO): READING 2025-03-25T21:26:39.027Z,1742937999.027 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:39.028Z,1742937999.028 [Depth_Keller](INFO): READING 2025-03-25T21:26:39.410Z,1742937999.410 [Depth_Keller](INFO): READING 2025-03-25T21:26:39.420Z,1742937999.420 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:39.420Z,1742937999.420 [Depth_Keller](INFO): READING 2025-03-25T21:26:39.813Z,1742937999.813 [Depth_Keller](INFO): READING 2025-03-25T21:26:39.824Z,1742937999.824 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:39.824Z,1742937999.824 [Depth_Keller](INFO): READING 2025-03-25T21:26:40.218Z,1742938000.218 [Depth_Keller](INFO): READING 2025-03-25T21:26:40.228Z,1742938000.228 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:40.228Z,1742938000.228 [Depth_Keller](INFO): READING 2025-03-25T21:26:40.621Z,1742938000.621 [Depth_Keller](INFO): READING 2025-03-25T21:26:40.646Z,1742938000.646 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:40.647Z,1742938000.647 [Depth_Keller](INFO): READING 2025-03-25T21:26:41.025Z,1742938001.025 [Depth_Keller](INFO): READING 2025-03-25T21:26:41.038Z,1742938001.038 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:41.038Z,1742938001.038 [Depth_Keller](INFO): READING 2025-03-25T21:26:41.429Z,1742938001.429 [Depth_Keller](INFO): READING 2025-03-25T21:26:41.433Z,1742938001.433 [NAL9602](INFO): Powering up NAL9602 2025-03-25T21:26:41.439Z,1742938001.439 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:41.439Z,1742938001.439 [Depth_Keller](INFO): READING 2025-03-25T21:26:41.856Z,1742938001.856 [Depth_Keller](INFO): READING 2025-03-25T21:26:41.876Z,1742938001.876 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:41.877Z,1742938001.877 [Depth_Keller](INFO): READING 2025-03-25T21:26:42.269Z,1742938002.269 [Depth_Keller](INFO): READING 2025-03-25T21:26:42.511Z,1742938002.511 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:42.512Z,1742938002.512 [Depth_Keller](INFO): READING 2025-03-25T21:26:42.707Z,1742938002.707 [Depth_Keller](INFO): READING 2025-03-25T21:26:42.764Z,1742938002.764 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:42.764Z,1742938002.764 [Depth_Keller](INFO): READING 2025-03-25T21:26:43.078Z,1742938003.078 [Depth_Keller](INFO): READING 2025-03-25T21:26:43.224Z,1742938003.224 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:43.224Z,1742938003.224 [Depth_Keller](INFO): READING 2025-03-25T21:26:43.482Z,1742938003.482 [Depth_Keller](INFO): READING 2025-03-25T21:26:43.501Z,1742938003.501 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:43.501Z,1742938003.501 [Depth_Keller](INFO): READING 2025-03-25T21:26:43.869Z,1742938003.869 [Depth_Keller](INFO): READING 2025-03-25T21:26:43.925Z,1742938003.925 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:43.925Z,1742938003.925 [Depth_Keller](INFO): READING 2025-03-25T21:26:44.279Z,1742938004.279 [Depth_Keller](INFO): READING 2025-03-25T21:26:44.387Z,1742938004.387 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:44.387Z,1742938004.387 [Depth_Keller](INFO): READING 2025-03-25T21:26:44.389Z,1742938004.389 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:44.679Z,1742938004.679 [Depth_Keller](INFO): READING 2025-03-25T21:26:44.691Z,1742938004.691 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:44.692Z,1742938004.692 [Depth_Keller](INFO): READING 2025-03-25T21:26:45.096Z,1742938005.096 [Depth_Keller](INFO): READING 2025-03-25T21:26:45.097Z,1742938005.097 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:45.118Z,1742938005.118 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:45.118Z,1742938005.118 [Depth_Keller](INFO): READING 2025-03-25T21:26:45.486Z,1742938005.486 [Depth_Keller](INFO): READING 2025-03-25T21:26:45.498Z,1742938005.498 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:45.498Z,1742938005.498 [Depth_Keller](INFO): READING 2025-03-25T21:26:45.905Z,1742938005.905 [Depth_Keller](INFO): READING 2025-03-25T21:26:46.122Z,1742938006.122 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:46.122Z,1742938006.122 [Depth_Keller](INFO): READING 2025-03-25T21:26:46.123Z,1742938006.123 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m 2025-03-25T21:26:46.298Z,1742938006.298 [Depth_Keller](INFO): READING 2025-03-25T21:26:46.324Z,1742938006.324 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:46.324Z,1742938006.324 [Depth_Keller](INFO): READING 2025-03-25T21:26:46.714Z,1742938006.714 [Depth_Keller](INFO): READING 2025-03-25T21:26:46.783Z,1742938006.783 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:46.783Z,1742938006.783 [Depth_Keller](INFO): READING 2025-03-25T21:26:47.102Z,1742938007.102 [Depth_Keller](INFO): READING 2025-03-25T21:26:47.126Z,1742938007.126 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:47.127Z,1742938007.127 [Depth_Keller](INFO): READING 2025-03-25T21:26:47.507Z,1742938007.507 [Depth_Keller](INFO): READING 2025-03-25T21:26:47.517Z,1742938007.517 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:47.517Z,1742938007.517 [Depth_Keller](INFO): READING 2025-03-25T21:26:47.909Z,1742938007.909 [Depth_Keller](INFO): READING 2025-03-25T21:26:47.953Z,1742938007.953 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:47.953Z,1742938007.953 [Depth_Keller](INFO): READING 2025-03-25T21:26:48.349Z,1742938008.349 [Depth_Keller](INFO): READING 2025-03-25T21:26:48.444Z,1742938008.444 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:48.444Z,1742938008.444 [Depth_Keller](INFO): READING 2025-03-25T21:26:48.743Z,1742938008.743 [Depth_Keller](INFO): READING 2025-03-25T21:26:48.758Z,1742938008.758 [Depth_Keller](INFO): STARTING READ 2025-03-25T21:26:48.758Z,1742938008.758 [Depth_Keller](INFO): READING 2025-03-25T21:26:49.121Z,1