2025-06-09T21:12:07.249Z,1749503527.249 [Supervisor](DEBUG): Initializing supervisor.
2025-06-09T21:12:07.254Z,1749503527.254 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-06-09T21:12:07.254Z,1749503527.254 [SyncHandler](INFO): Protected caller Thread ID is 798
2025-06-09T21:12:07.255Z,1749503527.255 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-06-09T21:12:07.256Z,1749503527.256 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-06-09T21:12:07.256Z,1749503527.256 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 799
2025-06-09T21:12:07.260Z,1749503527.260 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-06-09T21:12:07.281Z,1749503527.281 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-06-09T21:12:07.282Z,1749503527.282 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-06-09T21:12:07.282Z,1749503527.282 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 801
2025-06-09T21:12:07.287Z,1749503527.287 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-06-09T21:12:07.288Z,1749503527.288 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-06-09T21:12:07.288Z,1749503527.288 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802
2025-06-09T21:12:07.291Z,1749503527.291 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-06-09T21:12:07.292Z,1749503527.292 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-06-09T21:12:07.292Z,1749503527.292 [logger ThreadHandler](INFO): Protected caller Thread ID is 803
2025-06-09T21:12:07.296Z,1749503527.296 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-06-09T21:12:07.296Z,1749503527.296 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-06-09T21:12:07.303Z,1749503527.303 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-06-09T21:12:07.908Z,1749503527.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-06-09T21:12:07.910Z,1749503527.910 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-06-09T21:12:08.154Z,1749503528.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-06-09T21:12:08.155Z,1749503528.155 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-06-09T21:12:08.253Z,1749503528.253 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-06-09T21:12:08.516Z,1749503528.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-06-09T21:12:08.516Z,1749503528.516 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-06-09T21:12:08.612Z,1749503528.612 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-06-09T21:12:08.725Z,1749503528.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-06-09T21:12:08.727Z,1749503528.727 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-06-09T21:12:09.123Z,1749503529.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-06-09T21:12:09.124Z,1749503529.124 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-06-09T21:12:09.280Z,1749503529.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-06-09T21:12:09.281Z,1749503529.281 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-06-09T21:12:09.888Z,1749503529.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-06-09T21:12:09.889Z,1749503529.889 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-06-09T21:12:10.106Z,1749503530.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-06-09T21:12:10.107Z,1749503530.107 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-06-09T21:12:10.574Z,1749503530.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-06-09T21:12:10.575Z,1749503530.575 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-06-09T21:12:10.899Z,1749503530.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-06-09T21:12:10.900Z,1749503530.900 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-06-09T21:12:11.029Z,1749503531.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-06-09T21:12:11.030Z,1749503531.030 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-06-09T21:12:11.983Z,1749503531.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-06-09T21:12:11.985Z,1749503531.985 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-06-09T21:12:12.405Z,1749503532.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-06-09T21:12:12.406Z,1749503532.406 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-06-09T21:12:13.089Z,1749503533.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-06-09T21:12:13.091Z,1749503533.091 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2025-06-09T21:12:13.094Z,1749503533.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2025-06-09T21:12:13.234Z,1749503533.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2025-06-09T21:12:13.335Z,1749503533.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2025-06-09T21:12:13.497Z,1749503533.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2025-06-09T21:12:13.599Z,1749503533.599 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2025-06-09T21:12:13.891Z,1749503533.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-06-09T21:12:13.893Z,1749503533.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2025-06-09T21:12:14.018Z,1749503534.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2025-06-09T21:12:14.145Z,1749503534.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2025-06-09T21:12:14.267Z,1749503534.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2025-06-09T21:12:14.441Z,1749503534.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2025-06-09T21:12:14.557Z,1749503534.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2025-06-09T21:12:14.662Z,1749503534.662 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2025-06-09T21:12:14.662Z,1749503534.662 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-06-09T21:12:14.679Z,1749503534.679 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-06-09T21:12:14.695Z,1749503534.695 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-06-09T21:12:14.696Z,1749503534.696 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-06-09T21:12:14.872Z,1749503534.872 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-06-09T21:12:14.874Z,1749503534.874 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-06-09T21:12:14.959Z,1749503534.959 [VerticalControl](DEBUG): Construct VerticalControl.
2025-06-09T21:12:15.023Z,1749503535.023 [VerticalControl] Loaded
2025-06-09T21:12:15.024Z,1749503535.024 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-06-09T21:12:15.027Z,1749503535.027 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-06-09T21:12:15.075Z,1749503535.075 [HorizontalControl] Loaded
2025-06-09T21:12:15.075Z,1749503535.075 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-06-09T21:12:15.077Z,1749503535.077 [SpeedControl](DEBUG): Construct SpeedControl.
2025-06-09T21:12:15.080Z,1749503535.080 [SpeedControl] Loaded
2025-06-09T21:12:15.081Z,1749503535.081 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-06-09T21:12:15.083Z,1749503535.083 [LoopControl](DEBUG): Construct LoopControl.
2025-06-09T21:12:15.084Z,1749503535.084 [LoopControl] Loaded
2025-06-09T21:12:15.084Z,1749503535.084 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-06-09T21:12:15.085Z,1749503535.085 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-06-09T21:12:15.085Z,1749503535.085 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-06-09T21:12:15.271Z,1749503535.271 [BuoyancyServo] Loaded
2025-06-09T21:12:15.271Z,1749503535.271 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-06-09T21:12:15.295Z,1749503535.295 [ElevatorServo] Loaded
2025-06-09T21:12:15.295Z,1749503535.295 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-06-09T21:12:15.319Z,1749503535.319 [MassServo] Loaded
2025-06-09T21:12:15.319Z,1749503535.319 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-06-09T21:12:15.340Z,1749503535.340 [RudderServo] Loaded
2025-06-09T21:12:15.340Z,1749503535.340 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-06-09T21:12:15.358Z,1749503535.358 [ThrusterHE] Loaded
2025-06-09T21:12:15.358Z,1749503535.358 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-06-09T21:12:15.358Z,1749503535.358 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-06-09T21:12:15.359Z,1749503535.359 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-06-09T21:12:15.434Z,1749503535.434 [DepthRateCalculator] Loaded
2025-06-09T21:12:15.434Z,1749503535.434 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-06-09T21:12:15.439Z,1749503535.439 [PitchRateCalculator] Loaded
2025-06-09T21:12:15.440Z,1749503535.440 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-06-09T21:12:15.450Z,1749503535.450 [SpeedCalculator] Loaded
2025-06-09T21:12:15.450Z,1749503535.450 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-06-09T21:12:15.454Z,1749503535.454 [YawRateCalculator] Loaded
2025-06-09T21:12:15.455Z,1749503535.455 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-06-09T21:12:15.473Z,1749503535.473 [ElevatorOffsetCalculator] Loaded
2025-06-09T21:12:15.474Z,1749503535.474 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-06-09T21:12:15.474Z,1749503535.474 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-06-09T21:12:15.475Z,1749503535.475 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-06-09T21:12:15.624Z,1749503535.624 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-06-09T21:12:15.624Z,1749503535.624 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-06-09T21:12:15.639Z,1749503535.639 [NavChart] Loaded
2025-06-09T21:12:15.639Z,1749503535.639 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-06-09T21:12:15.645Z,1749503535.645 [UniversalFixResidualReporter] Loaded
2025-06-09T21:12:15.645Z,1749503535.645 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-06-09T21:12:15.646Z,1749503535.646 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-06-09T21:12:15.647Z,1749503535.647 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-06-09T21:12:16.464Z,1749503536.464 [AHRS_M2] Loaded
2025-06-09T21:12:16.464Z,1749503536.464 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-06-09T21:12:16.721Z,1749503536.721 [BackseatComponent] Loaded
2025-06-09T21:12:16.721Z,1749503536.721 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-06-09T21:12:16.722Z,1749503536.722 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409294E0
2025-06-09T21:12:16.722Z,1749503536.722 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 889
2025-06-09T21:12:16.725Z,1749503536.725 [LcmUniversalReporter] Loaded
2025-06-09T21:12:16.725Z,1749503536.725 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-06-09T21:12:16.732Z,1749503536.732 [BioacousticsDataBridge] Loaded
2025-06-09T21:12:16.732Z,1749503536.732 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-06-09T21:12:17.584Z,1749503537.584 [BPC1] Loaded
2025-06-09T21:12:17.585Z,1749503537.585 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-06-09T21:12:17.687Z,1749503537.687 [DAT] Loaded
2025-06-09T21:12:17.687Z,1749503537.687 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-06-09T21:12:17.688Z,1749503537.688 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409594E0
2025-06-09T21:12:17.689Z,1749503537.689 [DAT ThreadHandler](INFO): Protected caller Thread ID is 890
2025-06-09T21:12:17.825Z,1749503537.825 [DataOverHttps] Loaded
2025-06-09T21:12:17.825Z,1749503537.825 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-06-09T21:12:17.826Z,1749503537.826 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2025-06-09T21:12:17.827Z,1749503537.827 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 891
2025-06-09T21:12:17.885Z,1749503537.885 [Depth_Keller] Loaded
2025-06-09T21:12:17.885Z,1749503537.885 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-06-09T21:12:17.899Z,1749503537.899 [DropWeight] Loaded
2025-06-09T21:12:17.899Z,1749503537.899 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-06-09T21:12:18.029Z,1749503538.029 [NAL9602] Loaded
2025-06-09T21:12:18.029Z,1749503538.029 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-06-09T21:12:18.116Z,1749503538.116 [Onboard] Loaded
2025-06-09T21:12:18.116Z,1749503538.116 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-06-09T21:12:18.121Z,1749503538.121 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2025-06-09T21:12:18.121Z,1749503538.121 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 892
2025-06-09T21:12:18.198Z,1749503538.198 [Power24vConverter] Loaded
2025-06-09T21:12:18.198Z,1749503538.198 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-06-09T21:12:18.270Z,1749503538.270 [Radio_Surface] Loaded
2025-06-09T21:12:18.271Z,1749503538.271 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-06-09T21:12:18.285Z,1749503538.285 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0
2025-06-09T21:12:18.285Z,1749503538.285 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 893
2025-06-09T21:12:18.303Z,1749503538.303 [Sonardyne_Nano] Loaded
2025-06-09T21:12:18.303Z,1749503538.303 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-06-09T21:12:18.328Z,1749503538.328 [Waterlinked] Loaded
2025-06-09T21:12:18.328Z,1749503538.328 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-06-09T21:12:18.329Z,1749503538.329 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-06-09T21:12:18.330Z,1749503538.330 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-06-09T21:12:18.781Z,1749503538.781 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-06-09T21:12:18.782Z,1749503538.782 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-06-09T21:12:19.024Z,1749503539.024 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-06-09T21:12:19.025Z,1749503539.025 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-06-09T21:12:19.073Z,1749503539.073 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-06-09T21:12:19.074Z,1749503539.074 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-06-09T21:12:19.301Z,1749503539.301 [CTD_Seabird] Loaded
2025-06-09T21:12:19.302Z,1749503539.302 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-06-09T21:12:19.303Z,1749503539.303 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B2E4E0
2025-06-09T21:12:19.303Z,1749503539.303 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894
2025-06-09T21:12:19.327Z,1749503539.327 [PAR_Licor] Loaded
2025-06-09T21:12:19.328Z,1749503539.328 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-06-09T21:12:19.375Z,1749503539.375 [WetLabsBB2FL] Loaded
2025-06-09T21:12:19.376Z,1749503539.376 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-06-09T21:12:19.377Z,1749503539.377 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B5E4E0
2025-06-09T21:12:19.377Z,1749503539.377 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895
2025-06-09T21:12:19.378Z,1749503539.378 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-06-09T21:12:19.379Z,1749503539.379 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-06-09T21:12:19.825Z,1749503539.825 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-06-09T21:12:19.825Z,1749503539.825 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-06-09T21:12:20.038Z,1749503540.038 [SBIT](DEBUG): Construct Startup Built In Test.
2025-06-09T21:12:20.047Z,1749503540.047 [SBIT] Loaded
2025-06-09T21:12:20.047Z,1749503540.047 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-06-09T21:12:20.050Z,1749503540.050 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-06-09T21:12:20.064Z,1749503540.064 [IBIT] Loaded
2025-06-09T21:12:20.064Z,1749503540.064 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-06-09T21:12:20.069Z,1749503540.069 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-06-09T21:12:20.153Z,1749503540.153 [CBIT] Loaded
2025-06-09T21:12:20.154Z,1749503540.154 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-06-09T21:12:20.187Z,1749503540.187 [GFScanner] Loaded
2025-06-09T21:12:20.187Z,1749503540.187 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-06-09T21:12:20.188Z,1749503540.188 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-06-09T21:12:20.194Z,1749503540.194 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-06-09T21:12:20.197Z,1749503540.197 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-06-09T21:12:20.209Z,1749503540.209 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-06-09T21:12:20.210Z,1749503540.210 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-06-09T21:12:20.210Z,1749503540.210 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 896
2025-06-09T21:12:20.215Z,1749503540.215 [Supervisor](INFO): Main Thread ID is 796
2025-06-09T21:12:20.215Z,1749503540.215 [Supervisor](DEBUG): Running supervisor.
2025-06-09T21:12:20.215Z,1749503540.215 [CommandExec ThreadHandler](INFO): Handler Thread ID is 897
2025-06-09T21:12:20.216Z,1749503540.216 [CommandExec](INFO): Initializing the command executive.
2025-06-09T21:12:20.218Z,1749503540.218 [CommandLine ThreadHandler](INFO): Handler Thread ID is 898
2025-06-09T21:12:20.221Z,1749503540.221 [controlThread ThreadHandler](INFO): Handler Thread ID is 899
2025-06-09T21:12:20.222Z,1749503540.222 [controlThread](DEBUG): Initializing ControlThread
2025-06-09T21:12:20.223Z,1749503540.223 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-06-09T21:12:20.224Z,1749503540.224 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-06-09T21:12:20.225Z,1749503540.225 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-06-09T21:12:20.225Z,1749503540.225 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-06-09T21:12:20.227Z,1749503540.227 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-06-09T21:12:20.227Z,1749503540.227 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-06-09T21:12:20.228Z,1749503540.228 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-06-09T21:12:20.228Z,1749503540.228 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-06-09T21:12:20.228Z,1749503540.228 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-06-09T21:12:20.230Z,1749503540.230 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-06-09T21:12:20.230Z,1749503540.230 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-06-09T21:12:20.235Z,1749503540.235 [SBIT](INFO): Initialize SBIT Component.
2025-06-09T21:12:20.235Z,1749503540.235 [SBIT](IMPORTANT): git: 2025-05-16
2025-06-09T21:12:20.236Z,1749503540.236 [SBIT](INFO): git hash: 7cd925afbf924468d60d7bdc303f0aabfe74e3fc
2025-06-09T21:12:20.236Z,1749503540.236 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-06-09T21:12:20.237Z,1749503540.237 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-06-09T21:12:20.238Z,1749503540.238 [SBIT](INFO): Beginning SBIT in 53.000000 seconds.
2025-06-09T21:12:20.239Z,1749503540.239 [IBIT](INFO): Initialize IBIT Component.
2025-06-09T21:12:20.240Z,1749503540.240 [CBIT](DEBUG): Initialize CBIT Component.
2025-06-09T21:12:20.241Z,1749503540.241 [logger ThreadHandler](INFO): Handler Thread ID is 900
2025-06-09T21:12:20.251Z,1749503540.251 [CBIT](DEBUG): Initialized mux pins.
2025-06-09T21:12:20.251Z,1749503540.251 [CBIT](DEBUG): Initializing the watchdog timer.
2025-06-09T21:12:20.259Z,1749503540.259 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 901
2025-06-09T21:12:20.271Z,1749503540.271 [DAT ThreadHandler](INFO): Handler Thread ID is 902
2025-06-09T21:12:20.272Z,1749503540.272 [DAT](INFO): Powering up
2025-06-09T21:12:20.272Z,1749503540.272 [DAT](DEBUG): Initializing DAT.
2025-06-09T21:12:20.275Z,1749503540.275 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-06-09T21:12:20.275Z,1749503540.275 [CBIT](DEBUG): Initializing heartbeat.
2025-06-09T21:12:20.277Z,1749503540.277 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 904
2025-06-09T21:12:20.279Z,1749503540.279 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-06-09T21:12:20.287Z,1749503540.287 [Onboard ThreadHandler](INFO): Handler Thread ID is 905
2025-06-09T21:12:20.314Z,1749503540.314 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 906
2025-06-09T21:12:20.335Z,1749503540.335 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 907
2025-06-09T21:12:20.336Z,1749503540.336 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-06-09T21:12:20.341Z,1749503540.341 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 909
2025-06-09T21:12:20.343Z,1749503540.343 [WetLabsBB2FL](INFO): Powering up
2025-06-09T21:12:20.347Z,1749503540.347 [CBIT](DEBUG): Deactivating emergency mode.
2025-06-09T21:12:20.359Z,1749503540.359 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 911
2025-06-09T21:12:20.368Z,1749503540.368 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2025-06-09T21:12:20.368Z,1749503540.368 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2025-06-09T21:12:20.369Z,1749503540.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2025-06-09T21:12:20.369Z,1749503540.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2025-06-09T21:12:20.369Z,1749503540.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2025-06-09T21:12:20.369Z,1749503540.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2025-06-09T21:12:20.369Z,1749503540.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2025-06-09T21:12:20.369Z,1749503540.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2025-06-09T21:12:20.370Z,1749503540.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2025-06-09T21:12:20.370Z,1749503540.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2025-06-09T21:12:20.370Z,1749503540.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2025-06-09T21:12:20.370Z,1749503540.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2025-06-09T21:12:20.370Z,1749503540.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2025-06-09T21:12:20.371Z,1749503540.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2025-06-09T21:12:20.371Z,1749503540.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2025-06-09T21:12:20.371Z,1749503540.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2025-06-09T21:12:20.371Z,1749503540.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2025-06-09T21:12:20.371Z,1749503540.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2025-06-09T21:12:20.371Z,1749503540.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2025-06-09T21:12:20.372Z,1749503540.372 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2025-06-09T21:12:20.387Z,1749503540.387 [CBIT](DEBUG): Backplane powered.
2025-06-09T21:12:20.387Z,1749503540.387 [GFScanner](DEBUG): Initializing GFScanner
2025-06-09T21:12:20.387Z,1749503540.387 [GFScanner](DEBUG): Deactivating GF circuits.
2025-06-09T21:12:20.392Z,1749503540.392 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-06-09T21:12:20.392Z,1749503540.392 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-06-09T21:12:20.393Z,1749503540.393 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-06-09T21:12:20.399Z,1749503540.399 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-06-09T21:12:20.452Z,1749503540.452 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-06-09T21:12:20.476Z,1749503540.476 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-09T21:12:20.517Z,1749503540.517 [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-06-09T21:12:20.517Z,1749503540.517 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-06-09T21:12:20.517Z,1749503540.517 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-06-09T21:12:20.521Z,1749503540.521 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-06-09T21:12:20.723Z,1749503540.723 [Radio_Surface](INFO): Powering up
2025-06-09T21:12:20.872Z,1749503540.872 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-06-09T21:12:20.906Z,1749503540.906 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-06-09T21:12:20.908Z,1749503540.908 [Default:A.Wait](DEBUG): Construct Wait.
2025-06-09T21:12:20.910Z,1749503540.910 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-09T21:12:20.948Z,1749503540.948 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-06-09T21:12:20.950Z,1749503540.950 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-06-09T21:12:20.972Z,1749503540.972 [Default:E.Execute](DEBUG): Construct Execute.
2025-06-09T21:12:20.991Z,1749503540.991 [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-06-09T21:12:20.996Z,1749503540.996 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-06-09T21:12:21.020Z,1749503541.020 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-06-09T21:12:21.475Z,1749503541.475 [Depth_Keller](INFO): Initializing.
2025-06-09T21:12:21.476Z,1749503541.476 [Power24vConverter](INFO): Powering up.
2025-06-09T21:12:21.477Z,1749503541.477 [Sonardyne_Nano](INFO): Initializing.
2025-06-09T21:12:21.516Z,1749503541.516 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-09T21:12:21.615Z,1749503541.615 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-06-09T21:12:21.623Z,1749503541.623 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-06-09T21:12:21.624Z,1749503541.624 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-09T21:12:21.631Z,1749503541.631 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-09T21:12:21.632Z,1749503541.632 [MassServo](DEBUG): Initializing EZServoServo.
2025-06-09T21:12:21.639Z,1749503541.639 [MassServo](DEBUG): Initializing MassServo.
2025-06-09T21:12:21.640Z,1749503541.640 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-09T21:12:21.647Z,1749503541.647 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-09T21:12:21.648Z,1749503541.648 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-06-09T21:12:21.655Z,1749503541.655 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-06-09T21:12:21.898Z,1749503541.898 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-06-09T21:12:21.898Z,1749503541.898 [DropWeight] Hardware Fault, FailCount= 1
2025-06-09T21:12:21.898Z,1749503541.898 [DropWeight](ERROR): Hardware Fault
2025-06-09T21:12:21.922Z,1749503541.922 [CommandExec](FAULT): Scheduling is paused
2025-06-09T21:12:21.931Z,1749503541.931 [CBIT](INFO): Critical error at 20250609T211221
2025-06-09T21:12:21.931Z,1749503541.931 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-06-09T21:12:21.934Z,1749503541.934 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-06-09T21:12:21.934Z,1749503541.934 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-06-09T21:12:22.123Z,1749503542.123 [CBIT](INFO): Critical error at 20250609T211221
2025-06-09T21:12:22.955Z,1749503542.955 [WetLabsBB2FL](INFO): Powering down
2025-06-09T21:12:24.933Z,1749503544.933 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-06-09T21:12:25.826Z,1749503545.826 [Sonardyne_Nano](FAULT): Failed hardware self-test:>CKHW:FAIL;LBK

2025-06-09T21:12:25.826Z,1749503545.826 [Sonardyne_Nano] Hardware Fault, FailCount= 1
2025-06-09T21:12:25.826Z,1749503545.826 [Sonardyne_Nano](ERROR): Hardware Fault
2025-06-09T21:12:25.923Z,1749503545.923 [CBIT](ERROR): Hardware Fault in component: Sonardyne_Nano
2025-06-09T21:12:26.172Z,1749503546.172 [Sonardyne_Nano](INFO): Powering down
2025-06-09T21:12:27.171Z,1749503547.171 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-06-09T21:12:27.234Z,1749503547.234 [CBIT](INFO): Clearing failed state for component Sonardyne_Nano
2025-06-09T21:12:27.234Z,1749503547.234 [Sonardyne_Nano] No Fault, FailCount= 1
2025-06-09T21:12:27.380Z,1749503547.380 [Sonardyne_Nano](INFO): Initializing.
2025-06-09T21:12:29.401Z,1749503549.401 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-06-09T21:12:30.599Z,1749503550.599 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2025-06-09T21:12:30.986Z,1749503550.986 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-06-09T21:12:32.627Z,1749503552.627 [DAT](INFO): DAT read: 

2025-06-09T21:12:32.629Z,1749503552.629 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-06-09T21:12:34.392Z,1749503554.392 [DAT](INFO): DAT read: MF Frequency Band

2025-06-09T21:12:34.393Z,1749503554.393 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-06-09T21:12:34.393Z,1749503554.393 [DAT](INFO): DAT read: May 23 2025 21:01:07 

2025-06-09T21:12:35.400Z,1749503555.400 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-06-09T21:12:35.401Z,1749503555.401 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-06-09T21:12:35.401Z,1749503555.401 [DAT](INFO): commRate: 800
2025-06-09T21:12:37.467Z,1749503557.467 [DAT](INFO): entering command mode
2025-06-09T21:12:37.667Z,1749503557.667 [DAT](INFO): DAT read: 

2025-06-09T21:12:37.668Z,1749503557.668 [DAT](INFO): DAT read: use:1>

2025-06-09T21:12:37.668Z,1749503557.668 [DAT](INFO): unknown deviceResponse_: use:1>

2025-06-09T21:12:37.668Z,1749503557.668 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:37.824Z,1749503557.824 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.043943
2025-06-09T21:12:37.919Z,1749503557.919 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:38.171Z,1749503558.171 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:38.423Z,1749503558.423 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:38.675Z,1749503558.675 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:38.927Z,1749503558.927 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:39.179Z,1749503559.179 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:39.431Z,1749503559.431 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:39.683Z,1749503559.683 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:39.935Z,1749503559.935 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:40.187Z,1749503560.187 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:40.439Z,1749503560.439 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:40.691Z,1749503560.691 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:40.943Z,1749503560.943 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:41.195Z,1749503561.195 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:41.447Z,1749503561.447 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:41.699Z,1749503561.699 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:41.951Z,1749503561.951 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:42.203Z,1749503562.203 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:42.455Z,1749503562.455 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:42.707Z,1749503562.707 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:42.959Z,1749503562.959 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:43.211Z,1749503563.211 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:43.463Z,1749503563.463 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:43.715Z,1749503563.715 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:43.967Z,1749503563.967 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:44.219Z,1749503564.219 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:44.471Z,1749503564.471 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:44.723Z,1749503564.723 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:44.975Z,1749503564.975 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:45.227Z,1749503565.227 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:45.479Z,1749503565.479 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:45.731Z,1749503565.731 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:45.983Z,1749503565.983 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:46.235Z,1749503566.235 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:46.487Z,1749503566.487 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:46.739Z,1749503566.739 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:46.991Z,1749503566.991 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:47.243Z,1749503567.243 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:47.495Z,1749503567.495 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:47.747Z,1749503567.747 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:47.999Z,1749503567.999 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:48.251Z,1749503568.251 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:48.352Z,1749503568.352 [NAL9602](INFO): Powering up NAL9602
2025-06-09T21:12:48.504Z,1749503568.504 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:48.755Z,1749503568.755 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:49.007Z,1749503569.007 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:49.259Z,1749503569.259 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:49.511Z,1749503569.511 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:49.569Z,1749503569.569 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:12:49.569Z,1749503569.569 [Waterlinked] Communications Fault, FailCount= 1
2025-06-09T21:12:49.569Z,1749503569.569 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:12:49.585Z,1749503569.585 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:12:49.763Z,1749503569.763 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:50.015Z,1749503570.015 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:50.051Z,1749503570.051 [Waterlinked](INFO): Powering down
2025-06-09T21:12:50.267Z,1749503570.267 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:50.519Z,1749503570.519 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:50.771Z,1749503570.771 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:50.809Z,1749503570.809 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:12:50.809Z,1749503570.809 [Waterlinked] No Fault, FailCount= 1
2025-06-09T21:12:51.023Z,1749503571.023 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:51.275Z,1749503571.275 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:51.527Z,1749503571.527 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:51.779Z,1749503571.779 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:52.031Z,1749503572.031 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:52.283Z,1749503572.283 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:52.535Z,1749503572.535 [DAT](DEBUG): checking for command mode acknowledgment
2025-06-09T21:12:52.535Z,1749503572.535 [DAT](FAULT): failed to enter command mode
2025-06-09T21:12:52.843Z,1749503572.843 [DAT](INFO): entering command mode
2025-06-09T21:12:53.044Z,1749503573.044 [DAT](INFO): DAT read: user:1>

2025-06-09T21:12:53.045Z,1749503573.045 [DAT](INFO): DAT read: Command '+++' not found

2025-06-09T21:12:53.045Z,1749503573.045 [DAT](INFO): DAT read: Error

2025-06-09T21:12:53.045Z,1749503573.045 [DAT](INFO): setting verbose to 3
2025-06-09T21:12:53.295Z,1749503573.295 [DAT](INFO): DAT read: user:2>

2025-06-09T21:12:53.296Z,1749503573.296 [DAT](INFO): DAT read: Verbose         | 3           

2025-06-09T21:12:53.297Z,1749503573.297 [DAT](INFO): set verbose to 3
2025-06-09T21:12:53.297Z,1749503573.297 [DAT](INFO): setting DatVerbose to 27440
2025-06-09T21:12:53.547Z,1749503573.547 [DAT](INFO): DAT read: user:3>

2025-06-09T21:12:53.548Z,1749503573.548 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-06-09T21:12:53.549Z,1749503573.549 [DAT](INFO): set DatVerbose to 27440
2025-06-09T21:12:53.549Z,1749503573.549 [DAT](INFO): setting transmit power to 8
2025-06-09T21:12:53.799Z,1749503573.799 [DAT](INFO): DAT read: user:4>

2025-06-09T21:12:53.800Z,1749503573.800 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-06-09T21:12:53.801Z,1749503573.801 [DAT](INFO): set transmit power to 8
2025-06-09T21:12:53.801Z,1749503573.801 [DAT](INFO): setting local address to 12
2025-06-09T21:12:54.052Z,1749503574.052 [DAT](INFO): DAT read: user:5>

2025-06-09T21:12:54.052Z,1749503574.052 [DAT](INFO): DAT read: LocalAddr       | 12          

2025-06-09T21:12:54.053Z,1749503574.053 [DAT](INFO): set local address to 12
2025-06-09T21:12:54.054Z,1749503574.054 [DAT](INFO): Setting time to: 21:12:54 And date to:6/9/2025
2025-06-09T21:12:54.303Z,1749503574.303 [DAT](INFO): DAT read: user:6>

2025-06-09T21:12:54.304Z,1749503574.304 [DAT](INFO): DAT read: Mon Jun 9, 2025  21:12:54  

2025-06-09T21:12:54.305Z,1749503574.305 [DAT](INFO): Local DAT time set to Mon Jun 9, 2025  21:12:54  

2025-06-09T21:12:59.281Z,1749503579.281 [NAL9602](INFO): NAL9602 initialized
2025-06-09T21:13:06.265Z,1749503586.265 [CommandExec](IMPORTANT): got command report touch ElevatorServo.component_avgCurrent
2025-06-09T21:13:13.838Z,1749503593.838 [SBIT](IMPORTANT): Beginning Startup BIT
2025-06-09T21:13:13.843Z,1749503593.843 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-09T21:13:16.353Z,1749503596.353 [Reporter](INFO): ElevatorServo.component_avgCurrent 66.059998 mA
2025-06-09T21:13:17.756Z,1749503597.756 [Reporter](INFO): ElevatorServo.component_avgCurrent 0.000000 mA
2025-06-09T21:13:18.360Z,1749503598.360 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.004861
CHAN A1 (24V): -0.006948
CHAN A2 (12V): -0.004245
CHAN A3 (5V): -0.003287
CHAN B0 (3.3V): -0.000925
CHAN B1 (3.15aV): -0.000767
CHAN B2 (3.15bV): -0.000540
CHAN B3 (GND): -0.000906
OPEN: 0.004791
Full Scale: +/- 1 mA
2025-06-09T21:13:18.972Z,1749503598.972 [CommandExec](IMPORTANT): got command report touch ElevatorServo.component_current
2025-06-09T21:13:19.465Z,1749503599.465 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:13:19.465Z,1749503599.465 [Waterlinked] Communications Fault, FailCount= 2
2025-06-09T21:13:19.465Z,1749503599.465 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:13:19.520Z,1749503599.520 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:13:19.959Z,1749503599.959 [Waterlinked](INFO): Powering down
2025-06-09T21:13:20.728Z,1749503600.728 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:13:20.728Z,1749503600.728 [Waterlinked] No Fault, FailCount= 2
2025-06-09T21:13:26.077Z,1749503606.077 [CommandExec](IMPORTANT): got command report touch platform_elevator_angle
2025-06-09T21:13:27.538Z,1749503607.538 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:13:29.585Z,1749503609.585 [Reporter](INFO): platform_elevator_angle 0.262529 rad
2025-06-09T21:13:29.837Z,1749503609.837 [Reporter](INFO): platform_elevator_angle 0.250245 rad
2025-06-09T21:13:30.109Z,1749503610.109 [Reporter](INFO): platform_elevator_angle 0.210361 rad
2025-06-09T21:13:30.521Z,1749503610.521 [Reporter](INFO): ElevatorServo.component_avgCurrent 66.059998 mA
2025-06-09T21:13:30.521Z,1749503610.521 [Reporter](INFO): ElevatorServo.component_current 113.770004 mA
2025-06-09T21:13:30.522Z,1749503610.522 [Reporter](INFO): platform_elevator_angle 0.162339 rad
2025-06-09T21:13:30.937Z,1749503610.937 [Reporter](INFO): platform_elevator_angle 0.115737 rad
2025-06-09T21:13:31.330Z,1749503611.330 [Reporter](INFO): platform_elevator_angle 0.067254 rad
2025-06-09T21:13:31.725Z,1749503611.725 [Reporter](INFO): platform_elevator_angle 0.020767 rad
2025-06-09T21:13:32.125Z,1749503612.125 [Reporter](INFO): platform_elevator_angle -0.026257 rad
2025-06-09T21:13:32.535Z,1749503612.535 [Reporter](INFO): platform_elevator_angle -0.074624 rad
2025-06-09T21:13:32.948Z,1749503612.948 [Reporter](INFO): platform_elevator_angle -0.122915 rad
2025-06-09T21:13:33.413Z,1749503613.413 [Reporter](INFO): platform_elevator_angle -0.176619 rad
2025-06-09T21:13:33.761Z,1749503613.761 [Reporter](INFO): platform_elevator_angle -0.219152 rad
2025-06-09T21:13:34.174Z,1749503614.174 [Reporter](INFO): platform_elevator_angle -0.256733 rad
2025-06-09T21:13:34.809Z,1749503614.809 [Reporter](INFO): platform_elevator_angle -0.261953 rad
2025-06-09T21:13:35.174Z,1749503615.174 [Reporter](INFO): ElevatorServo.component_avgCurrent 0.000000 mA
2025-06-09T21:13:35.174Z,1749503615.174 [Reporter](INFO): ElevatorServo.component_current 0.000000 mA
2025-06-09T21:13:39.705Z,1749503619.705 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:13:41.325Z,1749503621.325 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-06-09T21:13:49.420Z,1749503629.420 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:13:49.420Z,1749503629.420 [Waterlinked] Communications Fault, FailCount= 3
2025-06-09T21:13:49.420Z,1749503629.420 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:13:49.483Z,1749503629.483 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:13:49.483Z,1749503629.483 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-06-09T21:13:49.919Z,1749503629.919 [Waterlinked](INFO): Powering down
2025-06-09T21:13:49.939Z,1749503629.939 [CBIT](INFO): Critical error at 20250609T211349
2025-06-09T21:13:52.634Z,1749503632.634 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:13:56.270Z,1749503636.270 [Reporter](INFO): platform_elevator_angle -0.261992 rad
2025-06-09T21:13:56.505Z,1749503636.505 [Reporter](INFO): platform_elevator_angle -0.252126 rad
2025-06-09T21:13:56.813Z,1749503636.813 [Reporter](INFO): platform_elevator_angle -0.206062 rad
2025-06-09T21:13:57.225Z,1749503637.225 [Reporter](INFO): platform_elevator_angle -0.159383 rad
2025-06-09T21:13:57.637Z,1749503637.637 [Reporter](INFO): platform_elevator_angle -0.111476 rad
2025-06-09T21:13:58.017Z,1749503638.017 [Reporter](INFO): platform_elevator_angle -0.066678 rad
2025-06-09T21:13:58.419Z,1749503638.419 [Reporter](INFO): platform_elevator_angle -0.019347 rad
2025-06-09T21:13:58.941Z,1749503638.941 [Reporter](INFO): platform_elevator_angle 0.000883 rad
2025-06-09T21:13:59.416Z,1749503639.416 [Reporter](INFO): platform_elevator_angle -0.000345 rad
2025-06-09T21:13:59.697Z,1749503639.697 [Reporter](INFO): ElevatorServo.component_avgCurrent 0.000000 mA
2025-06-09T21:13:59.698Z,1749503639.698 [Reporter](INFO): ElevatorServo.component_current 0.000000 mA
2025-06-09T21:14:04.765Z,1749503644.765 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:14:06.591Z,1749503646.591 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree
2025-06-09T21:14:06.595Z,1749503646.595 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2025-06-09T21:14:06.834Z,1749503646.834 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-06-09T21:14:08.045Z,1749503648.045 [SBIT](IMPORTANT): SBIT PASSED
2025-06-09T21:14:08.045Z,1749503648.045 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-06-09T21:14:08.046Z,1749503648.046 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;

2025-06-09T21:14:08.046Z,1749503648.046 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2025-06-09T21:14:08.046Z,1749503648.046 [SBIT](IMPORTANT): DAT.simulateHardware=0 bool;

2025-06-09T21:14:08.046Z,1749503648.046 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2025-06-09T21:14:08.046Z,1749503648.046 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2025-06-09T21:14:08.046Z,1749503648.046 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2025-06-09T21:14:08.047Z,1749503648.047 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.750000 m/s;

2025-06-09T21:14:08.047Z,1749503648.047 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=196.400102 cubic_centimeter;

2025-06-09T21:14:08.047Z,1749503648.047 [SBIT](IMPORTANT): VerticalControl.massDefault=8.446204 millimeter;

2025-06-09T21:14:08.047Z,1749503648.047 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=1 bool;

2025-06-09T21:14:08.428Z,1749503648.428 [MissionManager](IMPORTANT): Started mission Startup
2025-06-09T21:14:08.428Z,1749503648.428 [Startup] Running Loop=1
2025-06-09T21:14:08.429Z,1749503648.429 [Startup](DEBUG): Aggregate::initialize Startup
2025-06-09T21:14:08.429Z,1749503648.429 [Startup:A.GoToSurface] Running Loop=1
2025-06-09T21:14:08.429Z,1749503648.429 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-09T21:14:08.429Z,1749503648.429 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-09T21:14:08.430Z,1749503648.430 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-09T21:14:08.430Z,1749503648.430 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-09T21:14:08.451Z,1749503648.451 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-09T21:14:08.451Z,1749503648.451 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-09T21:14:08.452Z,1749503648.452 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-09T21:14:08.453Z,1749503648.453 [Startup:StartupSatComms] Running Loop=1
2025-06-09T21:14:08.453Z,1749503648.453 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-06-09T21:14:08.453Z,1749503648.453 [Startup:StartupSatComms:A] Running Loop=1
2025-06-09T21:14:08.613Z,1749503648.613 [Reporter](INFO): platform_elevator_angle 0.000269 rad
2025-06-09T21:14:08.837Z,1749503648.837 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-06-09T21:14:08.885Z,1749503648.885 [Reporter](INFO): platform_elevator_angle 0.007639 rad
2025-06-09T21:14:09.289Z,1749503649.289 [Reporter](INFO): platform_elevator_angle 0.047600 rad
2025-06-09T21:14:09.832Z,1749503649.832 [Reporter](INFO): platform_elevator_angle 0.095353 rad
2025-06-09T21:14:10.129Z,1749503650.129 [Reporter](INFO): platform_elevator_angle 0.143452 rad
2025-06-09T21:14:10.529Z,1749503650.529 [Reporter](INFO): platform_elevator_angle 0.190093 rad
2025-06-09T21:14:11.040Z,1749503651.040 [Reporter](INFO): platform_elevator_angle 0.238576 rad
2025-06-09T21:14:11.440Z,1749503651.440 [Reporter](INFO): platform_elevator_angle 0.262107 rad
2025-06-09T21:14:11.809Z,1749503651.809 [Reporter](INFO): ElevatorServo.component_avgCurrent 0.000000 mA
2025-06-09T21:14:11.809Z,1749503651.809 [Reporter](INFO): ElevatorServo.component_current 0.000000 mA
2025-06-09T21:14:17.290Z,1749503657.290 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:14:30.217Z,1749503670.217 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:14:33.171Z,1749503673.171 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -16 degree
2025-06-09T21:14:34.825Z,1749503674.825 [Reporter](INFO): platform_elevator_angle 0.262529 rad
2025-06-09T21:14:35.192Z,1749503675.192 [Reporter](INFO): platform_elevator_angle 0.249324 rad
2025-06-09T21:14:35.549Z,1749503675.549 [Reporter](INFO): platform_elevator_angle 0.209094 rad
2025-06-09T21:14:35.949Z,1749503675.949 [Reporter](INFO): platform_elevator_angle 0.161763 rad
2025-06-09T21:14:36.387Z,1749503676.387 [Reporter](INFO): platform_elevator_angle 0.111015 rad
2025-06-09T21:14:36.757Z,1749503676.757 [Reporter](INFO): platform_elevator_angle 0.066640 rad
2025-06-09T21:14:37.167Z,1749503677.167 [Reporter](INFO): ElevatorServo.component_avgCurrent 91.750000 mA
2025-06-09T21:14:37.167Z,1749503677.167 [Reporter](INFO): ElevatorServo.component_current 117.440002 mA
2025-06-09T21:14:37.168Z,1749503677.168 [Reporter](INFO): platform_elevator_angle 0.019961 rad
2025-06-09T21:14:37.653Z,1749503677.653 [Reporter](INFO): platform_elevator_angle -0.037811 rad
2025-06-09T21:14:37.949Z,1749503677.949 [Reporter](INFO): platform_elevator_angle -0.072244 rad
2025-06-09T21:14:38.365Z,1749503678.365 [Reporter](INFO): platform_elevator_angle -0.120113 rad
2025-06-09T21:14:38.761Z,1749503678.761 [Reporter](INFO): platform_elevator_angle -0.168634 rad
2025-06-09T21:14:39.161Z,1749503679.161 [Reporter](INFO): platform_elevator_angle -0.214622 rad
2025-06-09T21:14:39.564Z,1749503679.564 [Reporter](INFO): ElevatorServo.component_avgCurrent 146.800003 mA
2025-06-09T21:14:39.564Z,1749503679.564 [Reporter](INFO): ElevatorServo.component_current 165.150009 mA
2025-06-09T21:14:39.565Z,1749503679.565 [Reporter](INFO): platform_elevator_angle -0.260763 rad
2025-06-09T21:14:40.089Z,1749503680.089 [Reporter](INFO): platform_elevator_angle -0.278575 rad
2025-06-09T21:14:40.469Z,1749503680.469 [Reporter](INFO): ElevatorServo.component_avgCurrent 0.000000 mA
2025-06-09T21:14:40.470Z,1749503680.470 [Reporter](INFO): ElevatorServo.component_current 0.000000 mA
2025-06-09T21:14:42.337Z,1749503682.337 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:15:08.689Z,1749503708.689 [Startup:StartupSatComms:A](INFO): Timed out from 2025-06-09T21:14:08.5Z
2025-06-09T21:15:08.689Z,1749503708.689 [Startup:StartupSatComms:A] Stopped
2025-06-09T21:15:08.689Z,1749503708.689 [Startup:StartupSatComms:B] Running Loop=1
2025-06-09T21:15:09.068Z,1749503709.068 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-09T21:15:16.416Z,1749503716.416 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250523T193540/Courier0042.lzma
2025-06-09T21:15:17.417Z,1749503717.417 [DataOverHttps](INFO): Moved sent file to Logs/20250523T193540/Courier0042.lzma.bak
2025-06-09T21:15:17.417Z,1749503717.417 [DataOverHttps](INFO): SBD MOMSN=25164579
2025-06-09T21:15:20.329Z,1749503720.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-06-09T21:15:20.329Z,1749503720.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T21:15:20.340Z,1749503720.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T21:15:20.764Z,1749503720.764 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T21:15:20.764Z,1749503720.764 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-06-09T21:15:32.621Z,1749503732.621 [CommandExec](IMPORTANT): got command show variable avg_current
2025-06-09T21:15:33.247Z,1749503733.247 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250609T211207/Courier0000.lzma
2025-06-09T21:15:34.248Z,1749503734.248 [DataOverHttps](INFO): Moved sent file to Logs/20250609T211207/Courier0000.lzma.bak
2025-06-09T21:15:34.249Z,1749503734.249 [DataOverHttps](INFO): SBD MOMSN=25164582
2025-06-09T21:15:50.028Z,1749503750.028 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250523T193540/Express0043.lzma
2025-06-09T21:15:51.029Z,1749503751.029 [DataOverHttps](INFO): Moved sent file to Logs/20250523T193540/Express0043.lzma.bak
2025-06-09T21:15:51.029Z,1749503751.029 [DataOverHttps](INFO): SBD MOMSN=25164592
2025-06-09T21:16:06.732Z,1749503766.732 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250523T193540/Express0046.lzma
2025-06-09T21:16:07.733Z,1749503767.733 [DataOverHttps](INFO): Moved sent file to Logs/20250523T193540/Express0046.lzma.bak
2025-06-09T21:16:07.733Z,1749503767.733 [DataOverHttps](INFO): SBD MOMSN=25164617
2025-06-09T21:16:08.845Z,1749503768.845 [Startup:StartupSatComms:B](INFO): Timed out from 2025-06-09T21:15:08.7Z
2025-06-09T21:16:08.845Z,1749503768.845 [Startup:StartupSatComms:B] Stopped
2025-06-09T21:16:08.845Z,1749503768.845 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-06-09T21:16:08.846Z,1749503768.846 [Startup:StartupSatComms] Stopped
2025-06-09T21:16:08.846Z,1749503768.846 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-06-09T21:16:08.851Z,1749503768.851 [Startup](INFO): Completed Startup
2025-06-09T21:16:08.851Z,1749503768.851 [MissionManager](INFO): Startup is completed.
2025-06-09T21:16:08.851Z,1749503768.851 [MissionManager](INFO): Uninitializing Mission Startup
2025-06-09T21:16:08.851Z,1749503768.851 [Startup] Stopped
2025-06-09T21:16:08.851Z,1749503768.851 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-06-09T21:16:08.851Z,1749503768.851 [Startup:A.GoToSurface] Stopped
2025-06-09T21:16:08.852Z,1749503768.852 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-09T21:16:09.247Z,1749503769.247 [MissionManager](IMPORTANT): Started mission Default
2025-06-09T21:16:09.248Z,1749503769.248 [Default] Running Loop=1
2025-06-09T21:16:09.248Z,1749503769.248 [Default](DEBUG): Aggregate::initialize Default
2025-06-09T21:16:09.248Z,1749503769.248 [Default:B.GoToSurface] Running Loop=1
2025-06-09T21:16:09.248Z,1749503769.248 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-09T21:16:09.248Z,1749503769.248 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-09T21:16:09.248Z,1749503769.248 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-09T21:16:09.249Z,1749503769.249 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-09T21:16:09.249Z,1749503769.249 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-09T21:16:09.249Z,1749503769.249 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-09T21:16:09.250Z,1749503769.250 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-09T21:16:09.250Z,1749503769.250 [Default:A.Wait] Running Loop=1
2025-06-09T21:16:09.250Z,1749503769.250 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-09T21:16:22.580Z,1749503782.580 [Default:A.Wait](INFO): Done Waiting.
2025-06-09T21:16:22.580Z,1749503782.580 [Default:A.Wait] Stopped
2025-06-09T21:16:22.580Z,1749503782.580 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T21:16:22.972Z,1749503782.972 [Default:CheckIn] Running Loop=1
2025-06-09T21:16:22.972Z,1749503782.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T21:16:22.973Z,1749503782.973 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T21:16:23.373Z,1749503783.373 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-09T21:16:42.575Z,1749503802.575 [CommandExec](IMPORTANT): got command report touch platform_average_current
2025-06-09T21:16:49.690Z,1749503809.690 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:16:49.691Z,1749503809.691 [Waterlinked] No Fault, FailCount= 3
2025-06-09T21:16:50.925Z,1749503810.925 [DataOverHttps](INFO): Sending 1197 bytes from file Logs/20250609T211207/Express0001.lzma
2025-06-09T21:16:51.929Z,1749503811.929 [DataOverHttps](INFO): Moved sent file to Logs/20250609T211207/Express0001.lzma.bak
2025-06-09T21:16:51.929Z,1749503811.929 [DataOverHttps](INFO): SBD MOMSN=25164649
2025-06-09T21:17:15.911Z,1749503835.911 [Reporter](INFO): platform_average_current 0.000000 mA
2025-06-09T21:17:18.324Z,1749503838.324 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:17:18.324Z,1749503838.324 [Waterlinked] Communications Fault, FailCount= 1
2025-06-09T21:17:18.324Z,1749503838.324 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:17:18.387Z,1749503838.387 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:17:18.819Z,1749503838.819 [Waterlinked](INFO): Powering down
2025-06-09T21:17:19.554Z,1749503839.554 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:17:19.554Z,1749503839.554 [Waterlinked] No Fault, FailCount= 1
2025-06-09T21:17:19.921Z,1749503839.921 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:17:23.557Z,1749503843.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:17:35.678Z,1749503855.678 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:17:38.505Z,1749503858.505 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:17:48.209Z,1749503868.209 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:17:48.209Z,1749503868.209 [Waterlinked] Communications Fault, FailCount= 2
2025-06-09T21:17:48.209Z,1749503868.209 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:17:48.227Z,1749503868.227 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:17:48.703Z,1749503868.703 [Waterlinked](INFO): Powering down
2025-06-09T21:17:49.462Z,1749503869.462 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:17:49.462Z,1749503869.462 [Waterlinked] No Fault, FailCount= 2
2025-06-09T21:17:50.625Z,1749503870.625 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:17:53.455Z,1749503873.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:02.753Z,1749503882.753 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-06-09T21:18:05.596Z,1749503885.596 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:08.805Z,1749503888.805 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:15.712Z,1749503895.712 [Reporter](INFO): platform_average_current 0.060104 mA
2025-06-09T21:18:18.102Z,1749503898.102 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:18:18.102Z,1749503898.102 [Waterlinked] Communications Fault, FailCount= 3
2025-06-09T21:18:18.102Z,1749503898.102 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:18:18.152Z,1749503898.152 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:18:18.152Z,1749503898.152 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-06-09T21:18:18.595Z,1749503898.595 [Waterlinked](INFO): Powering down
2025-06-09T21:18:18.618Z,1749503898.618 [CBIT](INFO): Critical error at 20250609T211818
2025-06-09T21:18:20.925Z,1749503900.925 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:21.366Z,1749503901.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-06-09T21:18:21.366Z,1749503901.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T21:18:21.377Z,1749503901.377 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T21:18:21.789Z,1749503901.789 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T21:18:21.789Z,1749503901.789 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-06-09T21:18:24.157Z,1749503904.157 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:35.873Z,1749503915.873 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:39.509Z,1749503919.509 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:51.227Z,1749503931.227 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:18:54.459Z,1749503934.459 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:19:06.577Z,1749503946.577 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:19:09.405Z,1749503949.405 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:19:15.928Z,1749503955.928 [Reporter](INFO): platform_average_current 0.000000 mA
2025-06-09T21:19:21.526Z,1749503961.526 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:19:24.759Z,1749503964.759 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:19:36.478Z,1749503976.478 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T21:19:40.109Z,1749503980.109 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T21:19:40.116Z,1749503980.116 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 5.
2025-06-09T21:19:40.120Z,1749503980.120 [BPC1](INFO): Received data from all battery sticks.
2025-06-09T21:20:15.733Z,1749504015.733 [Reporter](INFO): platform_average_current 0.000000 mA
2025-06-09T21:20:18.728Z,1749504018.728 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -16 degree
2025-06-09T21:21:15.904Z,1749504075.904 [Reporter](INFO): platform_average_current 0.000000 mA
2025-06-09T21:21:18.339Z,1749504078.339 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:21:18.339Z,1749504078.339 [Waterlinked] No Fault, FailCount= 3
2025-06-09T21:21:22.345Z,1749504082.345 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-06-09T21:21:22.345Z,1749504082.345 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T21:21:22.357Z,1749504082.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T21:21:22.766Z,1749504082.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T21:21:22.766Z,1749504082.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-06-09T21:21:23.144Z,1749504083.144 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-09T21:16:22.0Z
2025-06-09T21:21:23.145Z,1749504083.145 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T21:21:23.145Z,1749504083.145 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T21:21:23.552Z,1749504083.552 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-09T21:21:30.102Z,1749504090.102 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20250609T211207/Courier0004.lzma
2025-06-09T21:21:31.104Z,1749504091.104 [DataOverHttps](INFO): Moved sent file to Logs/20250609T211207/Courier0004.lzma.bak
2025-06-09T21:21:31.105Z,1749504091.105 [DataOverHttps](INFO): SBD MOMSN=25164707
2025-06-09T21:21:46.743Z,1749504106.743 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20250609T211207/Express0005.lzma
2025-06-09T21:21:46.973Z,1749504106.973 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:21:46.973Z,1749504106.973 [Waterlinked] Communications Fault, FailCount= 1
2025-06-09T21:21:46.973Z,1749504106.973 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:21:47.025Z,1749504107.025 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:21:47.464Z,1749504107.464 [Waterlinked](INFO): Powering down
2025-06-09T21:21:47.745Z,1749504107.745 [DataOverHttps](INFO): Moved sent file to Logs/20250609T211207/Express0005.lzma.bak
2025-06-09T21:21:47.745Z,1749504107.745 [DataOverHttps](INFO): SBD MOMSN=25164710
2025-06-09T21:21:48.215Z,1749504108.215 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:21:48.215Z,1749504108.215 [Waterlinked] No Fault, FailCount= 1
2025-06-09T21:21:49.036Z,1749504109.036 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T21:21:49.036Z,1749504109.036 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T21:21:49.036Z,1749504109.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T21:22:16.125Z,1749504136.125 [Reporter](INFO): platform_average_current 0.000000 mA
2025-06-09T21:22:16.865Z,1749504136.865 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:22:16.865Z,1749504136.865 [Waterlinked] Communications Fault, FailCount= 2
2025-06-09T21:22:16.865Z,1749504136.865 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:22:16.955Z,1749504136.955 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:22:17.355Z,1749504137.355 [Waterlinked](INFO): Powering down
2025-06-09T21:22:18.123Z,1749504138.123 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-06-09T21:22:18.123Z,1749504138.123 [Waterlinked] No Fault, FailCount= 2
2025-06-09T21:22:46.766Z,1749504166.766 [Waterlinked](FAULT): Could not initialize.
2025-06-09T21:22:46.766Z,1749504166.766 [Waterlinked] Communications Fault, FailCount= 3
2025-06-09T21:22:46.766Z,1749504166.766 [Waterlinked](ERROR): Communications Fault
2025-06-09T21:22:46.829Z,1749504166.829 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-06-09T21:22:46.829Z,1749504166.829 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-06-09T21:22:47.255Z,1749504167.255 [Waterlinked](INFO): Powering down
2025-06-09T21:22:47.276Z,1749504167.276 [CBIT](INFO): Critical error at 20250609T212246
2025-06-09T21:23:00.500Z,1749504180.500 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-09T21:23:00.500Z,1749504180.500 [NAL9602] Data Fault, FailCount= 1
2025-06-09T21:23:00.500Z,1749504180.500 [NAL9602](ERROR): Data Fault
2025-06-09T21:23:00.539Z,1749504180.539 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-09T21:23:00.916Z,1749504180.916 [NAL9602](INFO): Powering down
2025-06-09T21:23:01.736Z,1749504181.736 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-09T21:23:01.736Z,1749504181.736 [NAL9602] No Fault, FailCount= 1
2025-06-09T21:23:15.927Z,1749504195.927 [Reporter](INFO): platform_average_current 0.000000 mA
2025-06-09T21:23:31.204Z,1749504211.204 [NAL9602](INFO): Powering up NAL9602
2025-06-09T21:23:42.117Z,1749504222.117 [NAL9602](INFO): NAL9602 initialized
2025-06-09T21:24:00.139Z,1749504240.139 [CommandExec](IMPORTANT): got command quit
2025-06-09T21:24:01.143Z,1749504241.143 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:01.143Z,1749504241.143 [CommandExec](INFO): Uninitializing the command executive.
2025-06-09T21:24:01.143Z,1749504241.143 [CommandExec](INFO): Uninitializing the command scheduler.
2025-06-09T21:24:01.143Z,1749504241.143 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:01.183Z,1749504241.183 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-06-09T21:24:01.183Z,1749504241.183 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-06-09T21:24:01.183Z,1749504241.183 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:01.184Z,1749504241.184 [NavChartDb](INFO): Join timeout helper Thread ID is 946
2025-06-09T21:24:01.235Z,1749504241.235 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:01.235Z,1749504241.235 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:01.255Z,1749504241.255 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-06-09T21:24:01.255Z,1749504241.255 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:01.255Z,1749504241.255 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 947
2025-06-09T21:24:01.719Z,1749504241.719 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:01.719Z,1749504241.719 [WetLabsBB2FL](INFO): Powering down
2025-06-09T21:24:01.720Z,1749504241.720 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:01.723Z,1749504241.723 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-06-09T21:24:01.723Z,1749504241.723 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:01.723Z,1749504241.723 [CTD_Seabird](INFO): Join timeout helper Thread ID is 948
2025-06-09T21:24:02.279Z,1749504242.279 [CTD_Seabird](INFO): Powering down
2025-06-09T21:24:02.291Z,1749504242.291 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:02.292Z,1749504242.292 [CTD_Seabird](INFO): Powering down
2025-06-09T21:24:02.307Z,1749504242.307 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:02.311Z,1749504242.311 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-06-09T21:24:02.311Z,1749504242.311 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:02.311Z,1749504242.311 [Radio_Surface](INFO): Join timeout helper Thread ID is 949
2025-06-09T21:24:02.503Z,1749504242.503 [Radio_Surface](INFO): Powering down
2025-06-09T21:24:02.504Z,1749504242.504 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:02.504Z,1749504242.504 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:02.515Z,1749504242.515 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-06-09T21:24:02.515Z,1749504242.515 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:02.515Z,1749504242.515 [Onboard](INFO): Join timeout helper Thread ID is 950
2025-06-09T21:24:02.903Z,1749504242.903 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-09T21:24:05.899Z,1749504245.899 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:05.899Z,1749504245.899 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:05.921Z,1749504245.921 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-06-09T21:24:05.921Z,1749504245.921 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:05.923Z,1749504245.923 [DataOverHttps](INFO): Join timeout helper Thread ID is 951
2025-06-09T21:24:05.935Z,1749504245.935 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:05.936Z,1749504245.936 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:05.948Z,1749504245.948 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-06-09T21:24:05.948Z,1749504245.948 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:05.948Z,1749504245.948 [DAT](INFO): Join timeout helper Thread ID is 952
2025-06-09T21:24:06.071Z,1749504246.071 [DAT](INFO): Powering down
2025-06-09T21:24:06.143Z,1749504246.143 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:06.143Z,1749504246.143 [DAT](INFO): Powering down
2025-06-09T21:24:06.144Z,1749504246.144 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.151Z,1749504246.151 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-06-09T21:24:06.151Z,1749504246.151 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.152Z,1749504246.152 [BackseatComponent](INFO): Join timeout helper Thread ID is 953
2025-06-09T21:24:06.203Z,1749504246.203 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:06.203Z,1749504246.203 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.211Z,1749504246.211 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-06-09T21:24:06.212Z,1749504246.212 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.212Z,1749504246.212 [logger](INFO): Join timeout helper Thread ID is 954
2025-06-09T21:24:06.225Z,1749504246.225 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:06.226Z,1749504246.226 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.243Z,1749504246.243 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-06-09T21:24:06.243Z,1749504246.243 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.244Z,1749504246.244 [CommandLine](INFO): Join timeout helper Thread ID is 955
2025-06-09T21:24:06.343Z,1749504246.343 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:06.343Z,1749504246.343 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.352Z,1749504246.352 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-06-09T21:24:06.352Z,1749504246.352 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.352Z,1749504246.352 [CommandExec](INFO): Join timeout helper Thread ID is 956
2025-06-09T21:24:06.356Z,1749504246.356 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-06-09T21:24:06.356Z,1749504246.356 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.356Z,1749504246.356 [controlThread](INFO): Join timeout helper Thread ID is 957
2025-06-09T21:24:06.391Z,1749504246.391 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-09T21:24:06.392Z,1749504246.392 [controlThread](DEBUG): Uninitializing ControlThread
2025-06-09T21:24:06.392Z,1749504246.392 [AHRS_M2](INFO): Powering down
2025-06-09T21:24:06.468Z,1749504246.468 [NAL9602](INFO): Powering down
2025-06-09T21:24:06.469Z,1749504246.469 [Sonardyne_Nano](INFO): Powering down
2025-06-09T21:24:06.587Z,1749504246.587 [Waterlinked](INFO): Powering down
2025-06-09T21:24:06.675Z,1749504246.675 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-06-09T21:24:06.676Z,1749504246.676 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-06-09T21:24:06.676Z,1749504246.676 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-06-09T21:24:06.677Z,1749504246.677 [MissionManager](INFO): Uninitializing Mission Default
2025-06-09T21:24:06.677Z,1749504246.677 [Default] Stopped
2025-06-09T21:24:06.677Z,1749504246.677 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-09T21:24:06.677Z,1749504246.677 [Default:B.GoToSurface] Stopped
2025-06-09T21:24:06.677Z,1749504246.677 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-09T21:24:06.677Z,1749504246.677 [Default:CheckIn] Stopped
2025-06-09T21:24:06.677Z,1749504246.677 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T21:24:06.677Z,1749504246.677 [Default:CheckIn:C.Wait] Stopped
2025-06-09T21:24:06.677Z,1749504246.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T21:24:06.680Z,1749504246.680 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-06-09T21:24:06.681Z,1749504246.681 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-06-09T21:24:06.681Z,1749504246.681 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-06-09T21:24:06.681Z,1749504246.681 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-06-09T21:24:06.682Z,1749504246.682 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-06-09T21:24:06.682Z,1749504246.682 [BuoyancyServo](INFO): Powering down
2025-06-09T21:24:06.695Z,1749504246.695 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-09T21:24:06.695Z,1749504246.695 [ElevatorServo](INFO): Powering down
2025-06-09T21:24:06.696Z,1749504246.696 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-06-09T21:24:06.696Z,1749504246.696 [MassServo](INFO): Powering down
2025-06-09T21:24:06.697Z,1749504246.697 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-06-09T21:24:06.697Z,1749504246.697 [RudderServo](INFO): Powering down
2025-06-09T21:24:06.697Z,1749504246.697 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-06-09T21:24:06.698Z,1749504246.698 [ThrusterHE](INFO): Powering down
2025-06-09T21:24:06.699Z,1749504246.699 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-06-09T21:24:06.699Z,1749504246.699 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-06-09T21:24:06.699Z,1749504246.699 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-06-09T21:24:06.699Z,1749504246.699 [CBIT](DEBUG): Powering off loads.
2025-06-09T21:24:06.710Z,1749504246.710 [CBIT](DEBUG): Disabling WDT.
2025-06-09T21:24:06.723Z,1749504246.723 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-09T21:24:06.723Z,1749504246.723 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-09T21:24:06.724Z,1749504246.724 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.784Z,1749504246.784 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.787Z,1749504246.787 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.793Z,1749504246.793 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.799Z,1749504246.799 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.842Z,1749504246.842 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.883Z,1749504246.883 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.888Z,1749504246.888 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.907Z,1749504246.907 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-09T21:24:06.907Z,1749504246.907 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-09T21:24:06.922Z,1749504246.922 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-09T21:24:06.973Z,1749504246.973 [logger ThreadHandler](INFO): Thread cancelled.