2025-03-20T18:04:37.959Z,1742493877.959 [Supervisor](DEBUG): Initializing supervisor. 2025-03-20T18:04:37.963Z,1742493877.963 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-20T18:04:37.964Z,1742493877.964 [SyncHandler](INFO): Protected caller Thread ID is 837 2025-03-20T18:04:37.964Z,1742493877.964 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-20T18:04:37.965Z,1742493877.965 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-20T18:04:37.965Z,1742493877.965 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2025-03-20T18:04:37.969Z,1742493877.969 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-20T18:04:37.990Z,1742493877.990 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-20T18:04:37.991Z,1742493877.991 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-20T18:04:37.991Z,1742493877.991 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2025-03-20T18:04:37.995Z,1742493877.995 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-20T18:04:37.996Z,1742493877.996 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-20T18:04:37.997Z,1742493877.997 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2025-03-20T18:04:37.999Z,1742493877.999 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-20T18:04:37.000Z,1742493878.000 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-20T18:04:38.000Z,1742493878.000 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2025-03-20T18:04:38.004Z,1742493878.004 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-20T18:04:38.005Z,1742493878.005 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-20T18:04:38.008Z,1742493878.008 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-20T18:04:38.360Z,1742493878.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-20T18:04:38.361Z,1742493878.361 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-20T18:04:38.454Z,1742493878.454 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-20T18:04:38.571Z,1742493878.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-20T18:04:38.572Z,1742493878.572 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-20T18:04:38.713Z,1742493878.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-20T18:04:38.714Z,1742493878.714 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-20T18:04:39.073Z,1742493879.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-20T18:04:39.074Z,1742493879.074 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-20T18:04:39.180Z,1742493879.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-20T18:04:39.182Z,1742493879.182 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-20T18:04:39.615Z,1742493879.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-20T18:04:39.616Z,1742493879.616 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-20T18:04:39.868Z,1742493879.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-20T18:04:39.869Z,1742493879.869 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-20T18:04:39.959Z,1742493879.959 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-20T18:04:40.213Z,1742493880.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-20T18:04:40.214Z,1742493880.214 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-20T18:04:40.447Z,1742493880.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-20T18:04:40.447Z,1742493880.447 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-20T18:04:40.992Z,1742493880.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-20T18:04:40.992Z,1742493880.992 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-20T18:04:41.428Z,1742493881.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-20T18:04:41.430Z,1742493881.430 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-20T18:04:41.727Z,1742493881.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-20T18:04:41.728Z,1742493881.728 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-20T18:04:42.614Z,1742493882.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-20T18:04:42.615Z,1742493882.615 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-20T18:04:43.197Z,1742493883.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-20T18:04:43.199Z,1742493883.199 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-03-20T18:04:43.201Z,1742493883.201 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-03-20T18:04:43.309Z,1742493883.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-03-20T18:04:43.454Z,1742493883.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-03-20T18:04:43.549Z,1742493883.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-03-20T18:04:43.667Z,1742493883.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-03-20T18:04:43.766Z,1742493883.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-03-20T18:04:44.041Z,1742493884.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-20T18:04:44.042Z,1742493884.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-03-20T18:04:44.162Z,1742493884.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-03-20T18:04:44.273Z,1742493884.273 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-03-20T18:04:44.429Z,1742493884.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-03-20T18:04:44.525Z,1742493884.525 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-03-20T18:04:44.528Z,1742493884.528 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-20T18:04:44.700Z,1742493884.700 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-20T18:04:44.701Z,1742493884.701 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-20T18:04:44.775Z,1742493884.775 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-20T18:04:44.839Z,1742493884.839 [VerticalControl] Loaded 2025-03-20T18:04:44.839Z,1742493884.839 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-20T18:04:44.842Z,1742493884.842 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-20T18:04:44.891Z,1742493884.891 [HorizontalControl] Loaded 2025-03-20T18:04:44.891Z,1742493884.891 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-20T18:04:44.894Z,1742493884.894 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-20T18:04:44.897Z,1742493884.897 [SpeedControl] Loaded 2025-03-20T18:04:44.897Z,1742493884.897 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-20T18:04:44.900Z,1742493884.900 [LoopControl](DEBUG): Construct LoopControl. 2025-03-20T18:04:44.900Z,1742493884.900 [LoopControl] Loaded 2025-03-20T18:04:44.901Z,1742493884.901 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-20T18:04:44.901Z,1742493884.901 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-20T18:04:44.903Z,1742493884.903 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-20T18:04:44.989Z,1742493884.989 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-20T18:04:44.990Z,1742493884.990 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-20T18:04:45.852Z,1742493885.852 [AHRS_M2] Loaded 2025-03-20T18:04:45.852Z,1742493885.852 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-20T18:04:46.107Z,1742493886.107 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4 2025-03-20T18:04:46.108Z,1742493886.108 [BackseatComponent] Loaded 2025-03-20T18:04:46.108Z,1742493886.108 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-03-20T18:04:46.109Z,1742493886.109 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-03-20T18:04:46.110Z,1742493886.110 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920 2025-03-20T18:04:46.112Z,1742493886.112 [LcmUniversalReporter] Loaded 2025-03-20T18:04:46.113Z,1742493886.113 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-03-20T18:04:46.942Z,1742493886.942 [BPC1] Loaded 2025-03-20T18:04:46.942Z,1742493886.942 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-20T18:04:47.039Z,1742493887.039 [DAT] Loaded 2025-03-20T18:04:47.040Z,1742493887.040 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-03-20T18:04:47.041Z,1742493887.041 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-03-20T18:04:47.041Z,1742493887.041 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921 2025-03-20T18:04:47.181Z,1742493887.181 [DataOverHttps] Loaded 2025-03-20T18:04:47.182Z,1742493887.182 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-20T18:04:47.183Z,1742493887.183 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-03-20T18:04:47.183Z,1742493887.183 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2025-03-20T18:04:47.206Z,1742493887.206 [Depth_Keller] Loaded 2025-03-20T18:04:47.207Z,1742493887.207 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-20T18:04:47.212Z,1742493887.212 [DropWeight] Loaded 2025-03-20T18:04:47.212Z,1742493887.212 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-20T18:04:47.278Z,1742493887.278 [NAL9602] Loaded 2025-03-20T18:04:47.278Z,1742493887.278 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-20T18:04:47.308Z,1742493887.308 [Onboard] Loaded 2025-03-20T18:04:47.309Z,1742493887.309 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-20T18:04:47.310Z,1742493887.310 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-03-20T18:04:47.310Z,1742493887.310 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2025-03-20T18:04:47.325Z,1742493887.325 [Power24vConverter] Loaded 2025-03-20T18:04:47.325Z,1742493887.325 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-20T18:04:47.341Z,1742493887.341 [Radio_Surface] Loaded 2025-03-20T18:04:47.342Z,1742493887.342 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-20T18:04:47.343Z,1742493887.343 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0 2025-03-20T18:04:47.343Z,1742493887.343 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2025-03-20T18:04:47.359Z,1742493887.359 [Sonardyne_Nano] Loaded 2025-03-20T18:04:47.359Z,1742493887.359 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-03-20T18:04:47.384Z,1742493887.384 [Waterlinked] Loaded 2025-03-20T18:04:47.385Z,1742493887.385 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-03-20T18:04:47.385Z,1742493887.385 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-20T18:04:47.386Z,1742493887.386 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-20T18:04:47.402Z,1742493887.402 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-20T18:04:47.403Z,1742493887.403 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-20T18:04:47.451Z,1742493887.451 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-20T18:04:47.451Z,1742493887.451 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-20T18:04:47.652Z,1742493887.652 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-20T18:04:47.662Z,1742493887.662 [SBIT] Loaded 2025-03-20T18:04:47.662Z,1742493887.662 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-20T18:04:47.665Z,1742493887.665 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-20T18:04:47.678Z,1742493887.678 [IBIT] Loaded 2025-03-20T18:04:47.678Z,1742493887.678 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-20T18:04:47.684Z,1742493887.684 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-20T18:04:47.765Z,1742493887.765 [CBIT] Loaded 2025-03-20T18:04:47.766Z,1742493887.766 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-20T18:04:47.798Z,1742493887.798 [GFScanner] Loaded 2025-03-20T18:04:47.799Z,1742493887.799 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-20T18:04:47.799Z,1742493887.799 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-20T18:04:47.800Z,1742493887.800 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-20T18:04:48.220Z,1742493888.220 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-20T18:04:48.221Z,1742493888.221 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-20T18:04:48.293Z,1742493888.293 [DepthRateCalculator] Loaded 2025-03-20T18:04:48.294Z,1742493888.294 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-20T18:04:48.299Z,1742493888.299 [PitchRateCalculator] Loaded 2025-03-20T18:04:48.299Z,1742493888.299 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-20T18:04:48.310Z,1742493888.310 [SpeedCalculator] Loaded 2025-03-20T18:04:48.310Z,1742493888.310 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-20T18:04:48.314Z,1742493888.314 [YawRateCalculator] Loaded 2025-03-20T18:04:48.315Z,1742493888.315 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-20T18:04:48.334Z,1742493888.334 [ElevatorOffsetCalculator] Loaded 2025-03-20T18:04:48.334Z,1742493888.334 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-20T18:04:48.335Z,1742493888.335 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-20T18:04:48.335Z,1742493888.335 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-20T18:04:48.559Z,1742493888.559 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-20T18:04:48.560Z,1742493888.560 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-20T18:04:48.700Z,1742493888.700 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-20T18:04:48.700Z,1742493888.700 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-20T18:04:48.714Z,1742493888.714 [NavChart] Loaded 2025-03-20T18:04:48.715Z,1742493888.715 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-20T18:04:48.721Z,1742493888.721 [UniversalFixResidualReporter] Loaded 2025-03-20T18:04:48.721Z,1742493888.721 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-20T18:04:48.721Z,1742493888.721 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-20T18:04:48.722Z,1742493888.722 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-20T18:04:48.894Z,1742493888.894 [BuoyancyServo] Loaded 2025-03-20T18:04:48.895Z,1742493888.895 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-20T18:04:48.918Z,1742493888.918 [ElevatorServo] Loaded 2025-03-20T18:04:48.918Z,1742493888.918 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-20T18:04:48.940Z,1742493888.940 [MassServo] Loaded 2025-03-20T18:04:48.941Z,1742493888.941 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-20T18:04:48.962Z,1742493888.962 [RudderServo] Loaded 2025-03-20T18:04:48.962Z,1742493888.962 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-20T18:04:48.980Z,1742493888.980 [ThrusterHE] Loaded 2025-03-20T18:04:48.980Z,1742493888.980 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-20T18:04:48.980Z,1742493888.980 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-20T18:04:48.981Z,1742493888.981 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-20T18:04:49.202Z,1742493889.202 [CTD_Seabird] Loaded 2025-03-20T18:04:49.202Z,1742493889.202 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-20T18:04:49.203Z,1742493889.203 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0 2025-03-20T18:04:49.203Z,1742493889.203 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925 2025-03-20T18:04:49.241Z,1742493889.241 [ESPComponent] Loaded 2025-03-20T18:04:49.241Z,1742493889.241 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-03-20T18:04:49.264Z,1742493889.264 [PAR_Licor] Loaded 2025-03-20T18:04:49.264Z,1742493889.264 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-20T18:04:49.311Z,1742493889.311 [WetLabsBB2FL] Loaded 2025-03-20T18:04:49.312Z,1742493889.312 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-03-20T18:04:49.313Z,1742493889.313 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-03-20T18:04:49.313Z,1742493889.313 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 926 2025-03-20T18:04:49.314Z,1742493889.314 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-20T18:04:49.320Z,1742493889.320 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-20T18:04:49.323Z,1742493889.323 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-20T18:04:49.335Z,1742493889.335 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-20T18:04:49.336Z,1742493889.336 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-03-20T18:04:49.336Z,1742493889.336 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927 2025-03-20T18:04:49.340Z,1742493889.340 [Supervisor](INFO): Main Thread ID is 828 2025-03-20T18:04:49.341Z,1742493889.341 [Supervisor](DEBUG): Running supervisor. 2025-03-20T18:04:49.341Z,1742493889.341 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928 2025-03-20T18:04:49.342Z,1742493889.342 [CommandExec](INFO): Initializing the command executive. 2025-03-20T18:04:49.343Z,1742493889.343 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929 2025-03-20T18:04:49.345Z,1742493889.345 [controlThread ThreadHandler](INFO): Handler Thread ID is 930 2025-03-20T18:04:49.346Z,1742493889.346 [controlThread](DEBUG): Initializing ControlThread 2025-03-20T18:04:49.347Z,1742493889.347 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-20T18:04:49.348Z,1742493889.348 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-20T18:04:49.349Z,1742493889.349 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-20T18:04:49.349Z,1742493889.349 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-20T18:04:49.355Z,1742493889.355 [SBIT](INFO): Initialize SBIT Component. 2025-03-20T18:04:49.356Z,1742493889.356 [SBIT](IMPORTANT): git: 2025-03-12_C-7-g9e16b3bff 2025-03-20T18:04:49.356Z,1742493889.356 [SBIT](INFO): git hash: 9e16b3bff22d42af46d832863712edbefb75a998 2025-03-20T18:04:49.356Z,1742493889.356 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-20T18:04:49.358Z,1742493889.358 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2025-03-20T18:04:49.359Z,1742493889.359 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2025-03-20T18:04:49.359Z,1742493889.359 [IBIT](INFO): Initialize IBIT Component. 2025-03-20T18:04:49.360Z,1742493889.360 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-20T18:04:49.361Z,1742493889.361 [logger ThreadHandler](INFO): Handler Thread ID is 931 2025-03-20T18:04:49.373Z,1742493889.373 [CBIT](DEBUG): Initialized mux pins. 2025-03-20T18:04:49.373Z,1742493889.373 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-03-20T18:04:49.373Z,1742493889.373 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-20T18:04:49.381Z,1742493889.381 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932 2025-03-20T18:04:49.393Z,1742493889.393 [DAT ThreadHandler](INFO): Handler Thread ID is 933 2025-03-20T18:04:49.394Z,1742493889.394 [DAT](INFO): Powering up 2025-03-20T18:04:49.394Z,1742493889.394 [DAT](DEBUG): Initializing DAT. 2025-03-20T18:04:49.397Z,1742493889.397 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-20T18:04:49.398Z,1742493889.398 [CBIT](DEBUG): Initializing heartbeat. 2025-03-20T18:04:49.399Z,1742493889.399 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935 2025-03-20T18:04:49.401Z,1742493889.401 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-20T18:04:49.410Z,1742493889.410 [Onboard ThreadHandler](INFO): Handler Thread ID is 936 2025-03-20T18:04:49.427Z,1742493889.427 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937 2025-03-20T18:04:49.446Z,1742493889.446 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938 2025-03-20T18:04:49.446Z,1742493889.446 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-20T18:04:49.450Z,1742493889.450 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940 2025-03-20T18:04:49.453Z,1742493889.453 [WetLabsBB2FL](INFO): Powering up 2025-03-20T18:04:49.455Z,1742493889.455 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942 2025-03-20T18:04:49.462Z,1742493889.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-20T18:04:49.462Z,1742493889.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-20T18:04:49.462Z,1742493889.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-20T18:04:49.462Z,1742493889.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-20T18:04:49.462Z,1742493889.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-20T18:04:49.462Z,1742493889.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-20T18:04:49.463Z,1742493889.463 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-20T18:04:49.463Z,1742493889.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-20T18:04:49.469Z,1742493889.469 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-20T18:04:49.509Z,1742493889.509 [CBIT](DEBUG): Backplane powered. 2025-03-20T18:04:49.509Z,1742493889.509 [GFScanner](DEBUG): Initializing GFScanner 2025-03-20T18:04:49.510Z,1742493889.510 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-20T18:04:49.510Z,1742493889.510 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-20T18:04:49.510Z,1742493889.510 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-20T18:04:49.511Z,1742493889.511 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-20T18:04:49.511Z,1742493889.511 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-20T18:04:49.512Z,1742493889.512 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-20T18:04:49.513Z,1742493889.513 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-20T18:04:49.518Z,1742493889.518 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-20T18:04:49.518Z,1742493889.518 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-20T18:04:49.519Z,1742493889.519 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-20T18:04:49.523Z,1742493889.523 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-20T18:04:49.606Z,1742493889.606 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-20T18:04:49.626Z,1742493889.626 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-20T18:04:49.658Z,1742493889.658 [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-20T18:04:49.658Z,1742493889.658 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-20T18:04:49.659Z,1742493889.659 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-20T18:04:49.674Z,1742493889.674 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-20T18:04:49.833Z,1742493889.833 [Radio_Surface](INFO): Powering up 2025-03-20T18:04:49.947Z,1742493889.947 [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-20T18:04:49.970Z,1742493889.970 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-20T18:04:49.971Z,1742493889.971 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-20T18:04:50.001Z,1742493890.001 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-20T18:04:50.034Z,1742493890.034 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-20T18:04:50.036Z,1742493890.036 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-20T18:04:50.058Z,1742493890.058 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-20T18:04:50.061Z,1742493890.061 [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-20T18:04:50.074Z,1742493890.074 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,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-20T18:04:50.097Z,1742493890.097 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-20T18:04:50.263Z,1742493890.263 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2025-03-20T18:04:50.264Z,1742493890.264 [Power24vConverter](INFO): Powering up. 2025-03-20T18:04:50.264Z,1742493890.264 [Sonardyne_Nano](INFO): Initializing. 2025-03-20T18:04:50.349Z,1742493890.349 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-03-20T18:04:50.371Z,1742493890.371 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:04:50.377Z,1742493890.377 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-20T18:04:50.378Z,1742493890.378 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:04:50.385Z,1742493890.385 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-20T18:04:50.387Z,1742493890.387 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:04:50.393Z,1742493890.393 [MassServo](DEBUG): Initializing MassServo. 2025-03-20T18:04:50.394Z,1742493890.394 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:04:50.403Z,1742493890.403 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-20T18:04:50.404Z,1742493890.404 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-20T18:04:50.409Z,1742493890.409 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-20T18:04:50.412Z,1742493890.412 [CBIT](FAULT): Main Battery Failure 2025-03-20T18:04:50.555Z,1742493890.555 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-20T18:04:50.555Z,1742493890.555 [DropWeight] Hardware Fault, FailCount= 1 2025-03-20T18:04:50.556Z,1742493890.556 [DropWeight](ERROR): Hardware Fault 2025-03-20T18:04:50.582Z,1742493890.582 [CommandExec](FAULT): Scheduling is paused 2025-03-20T18:04:50.583Z,1742493890.583 [CBIT](INFO): Critical error at 20250320T180450 2025-03-20T18:04:50.583Z,1742493890.583 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-03-20T18:04:50.594Z,1742493890.594 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-20T18:04:50.595Z,1742493890.595 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-20T18:04:50.919Z,1742493890.919 [CBIT](INFO): Critical error at 20250320T180450 2025-03-20T18:04:51.561Z,1742493891.561 [WetLabsBB2FL](INFO): Powering down 2025-03-20T18:04:52.507Z,1742493892.507 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-03-20T18:04:53.750Z,1742493893.750 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-03-20T18:04:56.142Z,1742493896.142 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-03-20T18:05:02.498Z,1742493902.498 [DAT](INFO): DAT read: 2025-03-20T18:05:02.499Z,1742493902.499 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-03-20T18:05:04.010Z,1742493904.010 [DAT](INFO): DAT read: MF Frequency Band 2025-03-20T18:05:04.011Z,1742493904.011 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-03-20T18:05:04.012Z,1742493904.012 [DAT](INFO): DAT read: Mar 20 2025 18:04:58 2025-03-20T18:05:05.270Z,1742493905.270 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-03-20T18:05:05.272Z,1742493905.272 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-03-20T18:05:05.272Z,1742493905.272 [DAT](INFO): commRate: 800 2025-03-20T18:05:07.337Z,1742493907.337 [DAT](INFO): entering command mode 2025-03-20T18:05:07.538Z,1742493907.538 [DAT](INFO): DAT read: user:1> 2025-03-20T18:05:07.538Z,1742493907.538 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:07.790Z,1742493907.790 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:08.041Z,1742493908.041 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:08.293Z,1742493908.293 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:08.546Z,1742493908.546 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:08.798Z,1742493908.798 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:09.050Z,1742493909.050 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:09.301Z,1742493909.301 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:09.553Z,1742493909.553 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:09.806Z,1742493909.806 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:10.058Z,1742493910.058 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:10.310Z,1742493910.310 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:10.561Z,1742493910.561 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:10.814Z,1742493910.814 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:11.066Z,1742493911.066 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:11.318Z,1742493911.318 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:11.569Z,1742493911.569 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:11.821Z,1742493911.821 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:12.074Z,1742493912.074 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:12.326Z,1742493912.326 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:12.578Z,1742493912.578 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:12.830Z,1742493912.830 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:13.082Z,1742493913.082 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:13.334Z,1742493913.334 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:13.586Z,1742493913.586 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:13.838Z,1742493913.838 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:14.090Z,1742493914.090 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:14.341Z,1742493914.341 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:14.594Z,1742493914.594 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:14.846Z,1742493914.846 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:15.098Z,1742493915.098 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:15.349Z,1742493915.349 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:15.606Z,1742493915.606 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:15.858Z,1742493915.858 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:16.110Z,1742493916.110 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:16.362Z,1742493916.362 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:16.613Z,1742493916.613 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:16.866Z,1742493916.866 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:17.118Z,1742493917.118 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:17.370Z,1742493917.370 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:17.622Z,1742493917.622 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:17.630Z,1742493917.630 [NAL9602](INFO): Powering up NAL9602 2025-03-20T18:05:17.875Z,1742493917.875 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:18.127Z,1742493918.127 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:18.379Z,1742493918.379 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:18.630Z,1742493918.630 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:18.883Z,1742493918.883 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:19.135Z,1742493919.135 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:19.387Z,1742493919.387 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:19.639Z,1742493919.639 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:19.891Z,1742493919.891 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:20.143Z,1742493920.143 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:20.394Z,1742493920.394 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:20.646Z,1742493920.646 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:20.899Z,1742493920.899 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:21.150Z,1742493921.150 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:21.402Z,1742493921.402 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:21.655Z,1742493921.655 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:21.907Z,1742493921.907 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:22.158Z,1742493922.158 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:22.410Z,1742493922.410 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-20T18:05:22.410Z,1742493922.410 [DAT](FAULT): failed to enter command mode 2025-03-20T18:05:22.564Z,1742493922.564 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,24052713 0 2025-03-20T18:05:22.713Z,1742493922.713 [DAT](INFO): entering command mode 2025-03-20T18:05:22.914Z,1742493922.914 [DAT](INFO): DAT read: user:1> 2025-03-20T18:05:22.915Z,1742493922.915 [DAT](INFO): DAT read: Command '+++' not found 2025-03-20T18:05:22.915Z,1742493922.915 [DAT](INFO): DAT read: Error 2025-03-20T18:05:22.915Z,1742493922.915 [DAT](INFO): setting verbose to 3 2025-03-20T18:05:23.166Z,1742493923.166 [DAT](INFO): DAT read: user:2> 2025-03-20T18:05:23.167Z,1742493923.167 [DAT](INFO): DAT read: Verbose | 3 2025-03-20T18:05:23.167Z,1742493923.167 [DAT](INFO): set verbose to 3 2025-03-20T18:05:23.167Z,1742493923.167 [DAT](INFO): setting DatVerbose to 27440 2025-03-20T18:05:23.418Z,1742493923.418 [DAT](INFO): DAT read: user:3> 2025-03-20T18:05:23.419Z,1742493923.419 [DAT](INFO): DAT read: DatVerbose | 27440 2025-03-20T18:05:23.419Z,1742493923.419 [DAT](INFO): set DatVerbose to 27440 2025-03-20T18:05:23.419Z,1742493923.419 [DAT](INFO): setting transmit power to 8 2025-03-20T18:05:23.670Z,1742493923.670 [DAT](INFO): DAT read: user:4> 2025-03-20T18:05:23.671Z,1742493923.671 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-03-20T18:05:23.671Z,1742493923.671 [DAT](INFO): set transmit power to 8 2025-03-20T18:05:23.671Z,1742493923.671 [DAT](INFO): setting local address to 1 2025-03-20T18:05:23.922Z,1742493923.922 [DAT](INFO): DAT read: user:5> 2025-03-20T18:05:23.923Z,1742493923.923 [DAT](INFO): DAT read: LocalAddr | 1 2025-03-20T18:05:23.923Z,1742493923.923 [DAT](INFO): set local address to 1 2025-03-20T18:05:23.924Z,1742493923.924 [DAT](INFO): Setting time to: 18:5:23 And date to:3/20/2025 2025-03-20T18:05:24.176Z,1742493924.176 [DAT](INFO): DAT read: user:6> 2025-03-20T18:05:24.179Z,1742493924.179 [DAT](INFO): DAT read: Thu Mar 20, 2025 18:05:23 2025-03-20T18:05:24.180Z,1742493924.180 [DAT](INFO): Local DAT time set to Thu Mar 20, 2025 18:05:23 2025-03-20T18:05:27.577Z,1742493927.577 [Waterlinked](INFO): Powering down 2025-03-20T18:05:28.711Z,1742493928.711 [NAL9602](INFO): NAL9602 initialized 2025-03-20T18:06:01.044Z,1742493961.044 [SBIT](IMPORTANT): Beginning Startup BIT 2025-03-20T18:06:24.746Z,1742493984.746 [CommandExec](IMPORTANT): got command failComponent 2025-