2025-02-05T00:46:06.920Z,1738716366.920 [Supervisor](DEBUG): Initializing supervisor. 2025-02-05T00:46:06.924Z,1738716366.924 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-02-05T00:46:06.925Z,1738716366.925 [SyncHandler](INFO): Protected caller Thread ID is 7716 2025-02-05T00:46:06.925Z,1738716366.925 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-02-05T00:46:06.926Z,1738716366.926 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-02-05T00:46:06.927Z,1738716366.927 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7717 2025-02-05T00:46:06.930Z,1738716366.930 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-02-05T00:46:06.947Z,1738716366.947 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-02-05T00:46:06.948Z,1738716366.948 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-02-05T00:46:06.948Z,1738716366.948 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7718 2025-02-05T00:46:06.953Z,1738716366.953 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-02-05T00:46:06.953Z,1738716366.953 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-02-05T00:46:06.954Z,1738716366.954 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7719 2025-02-05T00:46:06.956Z,1738716366.956 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-02-05T00:46:06.957Z,1738716366.957 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-02-05T00:46:06.957Z,1738716366.957 [logger ThreadHandler](INFO): Protected caller Thread ID is 7720 2025-02-05T00:46:06.961Z,1738716366.961 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-02-05T00:46:06.961Z,1738716366.961 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-02-05T00:46:06.963Z,1738716366.963 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-02-05T00:46:07.424Z,1738716367.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-02-05T00:46:07.425Z,1738716367.425 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-02-05T00:46:07.563Z,1738716367.563 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-02-05T00:46:07.732Z,1738716367.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-02-05T00:46:07.733Z,1738716367.733 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-02-05T00:46:07.933Z,1738716367.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-02-05T00:46:07.934Z,1738716367.934 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-02-05T00:46:08.452Z,1738716368.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-02-05T00:46:08.453Z,1738716368.453 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-02-05T00:46:08.609Z,1738716368.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-02-05T00:46:08.610Z,1738716368.610 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-02-05T00:46:09.242Z,1738716369.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-02-05T00:46:09.243Z,1738716369.243 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-02-05T00:46:09.647Z,1738716369.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-02-05T00:46:09.647Z,1738716369.647 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-02-05T00:46:09.809Z,1738716369.809 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-02-05T00:46:10.256Z,1738716370.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-02-05T00:46:10.257Z,1738716370.257 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-02-05T00:46:10.498Z,1738716370.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-02-05T00:46:10.499Z,1738716370.499 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-02-05T00:46:11.019Z,1738716371.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-02-05T00:46:11.020Z,1738716371.020 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-02-05T00:46:11.212Z,1738716371.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-02-05T00:46:11.213Z,1738716371.213 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-02-05T00:46:11.503Z,1738716371.503 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-02-05T00:46:11.503Z,1738716371.503 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-02-05T00:46:12.353Z,1738716372.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-02-05T00:46:12.354Z,1738716372.354 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-02-05T00:46:12.919Z,1738716372.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-02-05T00:46:12.921Z,1738716372.921 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-02-05T00:46:12.922Z,1738716372.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-02-05T00:46:13.049Z,1738716373.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-02-05T00:46:13.175Z,1738716373.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-02-05T00:46:13.271Z,1738716373.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-02-05T00:46:13.415Z,1738716373.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-02-05T00:46:13.513Z,1738716373.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-02-05T00:46:13.798Z,1738716373.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-02-05T00:46:13.799Z,1738716373.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-02-05T00:46:13.919Z,1738716373.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-02-05T00:46:14.027Z,1738716374.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-02-05T00:46:14.175Z,1738716374.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-02-05T00:46:14.271Z,1738716374.271 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-02-05T00:46:14.273Z,1738716374.273 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-02-05T00:46:14.405Z,1738716374.405 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-02-05T00:46:14.405Z,1738716374.405 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-02-05T00:46:14.457Z,1738716374.457 [VerticalControl](DEBUG): Construct VerticalControl. 2025-02-05T00:46:14.515Z,1738716374.515 [VerticalControl] Loaded 2025-02-05T00:46:14.515Z,1738716374.515 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-02-05T00:46:14.518Z,1738716374.518 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-02-05T00:46:14.564Z,1738716374.564 [HorizontalControl] Loaded 2025-02-05T00:46:14.565Z,1738716374.565 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-02-05T00:46:14.567Z,1738716374.567 [SpeedControl](DEBUG): Construct SpeedControl. 2025-02-05T00:46:14.570Z,1738716374.570 [SpeedControl] Loaded 2025-02-05T00:46:14.570Z,1738716374.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-02-05T00:46:14.573Z,1738716374.573 [LoopControl](DEBUG): Construct LoopControl. 2025-02-05T00:46:14.573Z,1738716374.573 [LoopControl] Loaded 2025-02-05T00:46:14.573Z,1738716374.573 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-02-05T00:46:14.574Z,1738716374.574 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-02-05T00:46:14.574Z,1738716374.574 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-02-05T00:46:14.630Z,1738716374.630 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-02-05T00:46:14.630Z,1738716374.630 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-02-05T00:46:15.077Z,1738716375.077 [AHRS_M2] Loaded 2025-02-05T00:46:15.077Z,1738716375.077 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-02-05T00:46:15.115Z,1738716375.115 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4 2025-02-05T00:46:15.116Z,1738716375.116 [BackseatComponent] Loaded 2025-02-05T00:46:15.116Z,1738716375.116 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-02-05T00:46:15.117Z,1738716375.117 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0 2025-02-05T00:46:15.117Z,1738716375.117 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7800 2025-02-05T00:46:15.120Z,1738716375.120 [LcmUniversalReporter] Loaded 2025-02-05T00:46:15.120Z,1738716375.120 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-02-05T00:46:15.924Z,1738716375.924 [BPC1] Loaded 2025-02-05T00:46:15.924Z,1738716375.924 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-02-05T00:46:16.019Z,1738716376.019 [DAT] Loaded 2025-02-05T00:46:16.019Z,1738716376.019 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-02-05T00:46:16.020Z,1738716376.020 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0 2025-02-05T00:46:16.021Z,1738716376.021 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7801 2025-02-05T00:46:16.093Z,1738716376.093 [DataOverHttps] Loaded 2025-02-05T00:46:16.093Z,1738716376.093 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-02-05T00:46:16.094Z,1738716376.094 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409164E0 2025-02-05T00:46:16.094Z,1738716376.094 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7802 2025-02-05T00:46:16.117Z,1738716376.117 [Depth_Keller] Loaded 2025-02-05T00:46:16.117Z,1738716376.117 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-02-05T00:46:16.122Z,1738716376.122 [DropWeight] Loaded 2025-02-05T00:46:16.122Z,1738716376.122 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-02-05T00:46:16.185Z,1738716376.185 [NAL9602] Loaded 2025-02-05T00:46:16.186Z,1738716376.186 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-02-05T00:46:16.214Z,1738716376.214 [Onboard] Loaded 2025-02-05T00:46:16.215Z,1738716376.215 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-02-05T00:46:16.215Z,1738716376.215 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409464E0 2025-02-05T00:46:16.216Z,1738716376.216 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7803 2025-02-05T00:46:16.230Z,1738716376.230 [Power24vConverter] Loaded 2025-02-05T00:46:16.230Z,1738716376.230 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-02-05T00:46:16.245Z,1738716376.245 [Radio_Surface] Loaded 2025-02-05T00:46:16.245Z,1738716376.245 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-02-05T00:46:16.246Z,1738716376.246 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409764E0 2025-02-05T00:46:16.247Z,1738716376.247 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7804 2025-02-05T00:46:16.262Z,1738716376.262 [Sonardyne_Nano] Loaded 2025-02-05T00:46:16.262Z,1738716376.262 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-02-05T00:46:16.285Z,1738716376.285 [Waterlinked] Loaded 2025-02-05T00:46:16.285Z,1738716376.285 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-02-05T00:46:16.286Z,1738716376.286 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-02-05T00:46:16.286Z,1738716376.286 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-02-05T00:46:16.296Z,1738716376.296 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-02-05T00:46:16.297Z,1738716376.297 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-02-05T00:46:16.323Z,1738716376.323 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-02-05T00:46:16.323Z,1738716376.323 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-02-05T00:46:16.462Z,1738716376.462 [SBIT](DEBUG): Construct Startup Built In Test. 2025-02-05T00:46:16.470Z,1738716376.470 [SBIT] Loaded 2025-02-05T00:46:16.471Z,1738716376.471 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-02-05T00:46:16.473Z,1738716376.473 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-02-05T00:46:16.486Z,1738716376.486 [IBIT] Loaded 2025-02-05T00:46:16.486Z,1738716376.486 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-02-05T00:46:16.492Z,1738716376.492 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-02-05T00:46:16.593Z,1738716376.593 [CBIT] Loaded 2025-02-05T00:46:16.594Z,1738716376.594 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-02-05T00:46:16.594Z,1738716376.594 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-02-05T00:46:16.595Z,1738716376.595 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-02-05T00:46:16.928Z,1738716376.928 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-02-05T00:46:16.928Z,1738716376.928 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-02-05T00:46:16.977Z,1738716376.977 [DepthRateCalculator] Loaded 2025-02-05T00:46:16.977Z,1738716376.977 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-02-05T00:46:16.983Z,1738716376.983 [PitchRateCalculator] Loaded 2025-02-05T00:46:16.983Z,1738716376.983 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-02-05T00:46:16.994Z,1738716376.994 [SpeedCalculator] Loaded 2025-02-05T00:46:16.994Z,1738716376.994 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-02-05T00:46:16.998Z,1738716376.998 [YawRateCalculator] Loaded 2025-02-05T00:46:16.998Z,1738716376.998 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-02-05T00:46:17.017Z,1738716377.017 [ElevatorOffsetCalculator] Loaded 2025-02-05T00:46:17.017Z,1738716377.017 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-02-05T00:46:17.018Z,1738716377.018 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-02-05T00:46:17.018Z,1738716377.018 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-02-05T00:46:17.188Z,1738716377.188 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-02-05T00:46:17.188Z,1738716377.188 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-02-05T00:46:17.297Z,1738716377.297 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-02-05T00:46:17.297Z,1738716377.297 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-02-05T00:46:17.310Z,1738716377.310 [NavChart] Loaded 2025-02-05T00:46:17.311Z,1738716377.311 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-02-05T00:46:17.316Z,1738716377.316 [UniversalFixResidualReporter] Loaded 2025-02-05T00:46:17.316Z,1738716377.316 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-02-05T00:46:17.317Z,1738716377.317 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-02-05T00:46:17.317Z,1738716377.317 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-02-05T00:46:17.449Z,1738716377.449 [BuoyancyServo] Loaded 2025-02-05T00:46:17.449Z,1738716377.449 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-02-05T00:46:17.472Z,1738716377.472 [ElevatorServo] Loaded 2025-02-05T00:46:17.472Z,1738716377.472 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-02-05T00:46:17.494Z,1738716377.494 [MassServo] Loaded 2025-02-05T00:46:17.494Z,1738716377.494 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-02-05T00:46:17.514Z,1738716377.514 [RudderServo] Loaded 2025-02-05T00:46:17.515Z,1738716377.515 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-02-05T00:46:17.531Z,1738716377.531 [ThrusterHE] Loaded 2025-02-05T00:46:17.531Z,1738716377.531 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-02-05T00:46:17.532Z,1738716377.532 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-02-05T00:46:17.532Z,1738716377.532 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-02-05T00:46:17.717Z,1738716377.717 [CTD_Seabird] Loaded 2025-02-05T00:46:17.717Z,1738716377.717 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-02-05T00:46:17.718Z,1738716377.718 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BEF4E0 2025-02-05T00:46:17.719Z,1738716377.719 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7805 2025-02-05T00:46:17.754Z,1738716377.754 [ESPComponent] Loaded 2025-02-05T00:46:17.755Z,1738716377.755 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-02-05T00:46:17.776Z,1738716377.776 [PAR_Licor] Loaded 2025-02-05T00:46:17.776Z,1738716377.776 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-02-05T00:46:17.820Z,1738716377.820 [WetLabsBB2FL] Loaded 2025-02-05T00:46:17.820Z,1738716377.820 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-02-05T00:46:17.821Z,1738716377.821 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C1F4E0 2025-02-05T00:46:17.822Z,1738716377.822 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7806 2025-02-05T00:46:17.823Z,1738716377.823 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-02-05T00:46:17.829Z,1738716377.829 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-02-05T00:46:17.832Z,1738716377.832 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-02-05T00:46:17.843Z,1738716377.843 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-02-05T00:46:17.844Z,1738716377.844 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C4F4E0 2025-02-05T00:46:17.844Z,1738716377.844 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7807 2025-02-05T00:46:17.848Z,1738716377.848 [Supervisor](INFO): Main Thread ID is 7715 2025-02-05T00:46:17.848Z,1738716377.848 [Supervisor](DEBUG): Running supervisor. 2025-02-05T00:46:17.849Z,1738716377.849 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7808 2025-02-05T00:46:17.850Z,1738716377.850 [CommandExec](INFO): Initializing the command executive. 2025-02-05T00:46:17.851Z,1738716377.851 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7809 2025-02-05T00:46:17.853Z,1738716377.853 [controlThread ThreadHandler](INFO): Handler Thread ID is 7810 2025-02-05T00:46:17.854Z,1738716377.854 [controlThread](DEBUG): Initializing ControlThread 2025-02-05T00:46:17.855Z,1738716377.855 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-02-05T00:46:17.856Z,1738716377.856 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-02-05T00:46:17.857Z,1738716377.857 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-02-05T00:46:17.857Z,1738716377.857 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-02-05T00:46:17.864Z,1738716377.864 [SBIT](INFO): Initialize SBIT Component. 2025-02-05T00:46:17.864Z,1738716377.864 [SBIT](IMPORTANT): git: 2025-02-03 2025-02-05T00:46:17.865Z,1738716377.865 [SBIT](INFO): git hash: 53755eb7ef5be6243977895b16b430e94c56a7b6 2025-02-05T00:46:17.865Z,1738716377.865 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-02-05T00:46:17.866Z,1738716377.866 [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-02-05T00:46:17.867Z,1738716377.867 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2025-02-05T00:46:17.868Z,1738716377.868 [IBIT](INFO): Initialize IBIT Component. 2025-02-05T00:46:17.869Z,1738716377.869 [CBIT](DEBUG): Initialize CBIT Component. 2025-02-05T00:46:17.870Z,1738716377.870 [logger ThreadHandler](INFO): Handler Thread ID is 7811 2025-02-05T00:46:17.882Z,1738716377.882 [CBIT](DEBUG): Initialized mux pins. 2025-02-05T00:46:17.882Z,1738716377.882 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-02-05T00:46:17.882Z,1738716377.882 [CBIT](DEBUG): Initializing the watchdog timer. 2025-02-05T00:46:17.890Z,1738716377.890 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7812 2025-02-05T00:46:17.902Z,1738716377.902 [DAT ThreadHandler](INFO): Handler Thread ID is 7813 2025-02-05T00:46:17.903Z,1738716377.903 [DAT](INFO): Powering up 2025-02-05T00:46:17.903Z,1738716377.903 [DAT](DEBUG): Initializing DAT. 2025-02-05T00:46:17.906Z,1738716377.906 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-02-05T00:46:17.906Z,1738716377.906 [CBIT](DEBUG): Initializing heartbeat. 2025-02-05T00:46:17.908Z,1738716377.908 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7815 2025-02-05T00:46:17.910Z,1738716377.910 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-02-05T00:46:17.918Z,1738716377.918 [Onboard ThreadHandler](INFO): Handler Thread ID is 7816 2025-02-05T00:46:17.936Z,1738716377.936 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7817 2025-02-05T00:46:17.955Z,1738716377.955 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7818 2025-02-05T00:46:17.955Z,1738716377.955 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-02-05T00:46:17.959Z,1738716377.959 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7820 2025-02-05T00:46:17.962Z,1738716377.962 [WetLabsBB2FL](INFO): Powering up 2025-02-05T00:46:17.963Z,1738716377.963 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7822 2025-02-05T00:46:17.967Z,1738716377.967 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-02-05T00:46:17.968Z,1738716377.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-02-05T00:46:17.968Z,1738716377.968 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-02-05T00:46:17.968Z,1738716377.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-02-05T00:46:17.968Z,1738716377.968 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-02-05T00:46:17.968Z,1738716377.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-02-05T00:46:17.968Z,1738716377.968 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-02-05T00:46:17.969Z,1738716377.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-02-05T00:46:17.978Z,1738716377.978 [CBIT](DEBUG): Deactivating GF circuits. 2025-02-05T00:46:17.978Z,1738716377.978 [CBIT](DEBUG): Deactivating emergency mode. 2025-02-05T00:46:18.018Z,1738716378.018 [CBIT](DEBUG): Backplane powered. 2025-02-05T00:46:18.018Z,1738716378.018 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-02-05T00:46:18.019Z,1738716378.019 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-02-05T00:46:18.019Z,1738716378.019 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-02-05T00:46:18.019Z,1738716378.019 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-02-05T00:46:18.020Z,1738716378.020 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-02-05T00:46:18.021Z,1738716378.021 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-02-05T00:46:18.021Z,1738716378.021 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-02-05T00:46:18.026Z,1738716378.026 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-02-05T00:46:18.027Z,1738716378.027 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-02-05T00:46:18.027Z,1738716378.027 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-02-05T00:46:18.030Z,1738716378.030 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-02-05T00:46:18.100Z,1738716378.100 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-02-05T00:46:18.125Z,1738716378.125 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-02-05T00:46:18.173Z,1738716378.173 [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-02-05T00:46:18.174Z,1738716378.174 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-02-05T00:46:18.174Z,1738716378.174 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-02-05T00:46:18.176Z,1738716378.176 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-02-05T00:46:18.342Z,1738716378.342 [Radio_Surface](INFO): Powering up 2025-02-05T00:46:18.486Z,1738716378.486 [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-02-05T00:46:18.492Z,1738716378.492 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-02-05T00:46:18.493Z,1738716378.493 [Default:A.Wait](DEBUG): Construct Wait. 2025-02-05T00:46:18.520Z,1738716378.520 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-02-05T00:46:18.552Z,1738716378.552 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-02-05T00:46:18.555Z,1738716378.555 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-02-05T00:46:18.570Z,1738716378.570 [Default:E.Execute](DEBUG): Construct Execute. 2025-02-05T00:46:18.573Z,1738716378.573 [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-02-05T00:46:18.590Z,1738716378.590 [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,LcmUniversalReporter,Reporter,LogSplitter, 2025-02-05T00:46:18.598Z,1738716378.598 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-02-05T00:46:18.663Z,1738716378.663 [Depth_Keller](ERROR): Pressure or depth reading out of range: 863.095886 decibar, 0.000000 m 2025-02-05T00:46:18.664Z,1738716378.664 [Power24vConverter](INFO): Powering up. 2025-02-05T00:46:18.665Z,1738716378.665 [Sonardyne_Nano](INFO): Initializing. 2025-02-05T00:46:18.696Z,1738716378.696 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-02-05T00:46:18.726Z,1738716378.726 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-02-05T00:46:18.734Z,1738716378.734 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-02-05T00:46:18.735Z,1738716378.735 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-02-05T00:46:18.742Z,1738716378.742 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-02-05T00:46:18.743Z,1738716378.743 [MassServo](DEBUG): Initializing EZServoServo. 2025-02-05T00:46:18.750Z,1738716378.750 [MassServo](DEBUG): Initializing MassServo. 2025-02-05T00:46:18.751Z,1738716378.751 [RudderServo](DEBUG): Initializing EZServoServo. 2025-02-05T00:46:18.758Z,1738716378.758 [RudderServo](DEBUG): Initializing RudderServo. 2025-02-05T00:46:18.759Z,1738716378.759 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-02-05T00:46:18.767Z,1738716378.767 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-02-05T00:46:20.070Z,1738716380.070 [WetLabsBB2FL](INFO): Powering down 2025-02-05T00:46:20.620Z,1738716380.620 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-02-05T00:46:21.898Z,1738716381.898 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-02-05T00:46:24.511Z,1738716384.511 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-02-05T00:46:32.052Z,1738716392.052 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004176 2025-02-05T00:46:32.787Z,1738716392.787 [DAT](INFO): DAT read: 2025-02-05T00:46:32.791Z,1738716392.791 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-02-05T00:46:34.299Z,1738716394.299 [DAT](INFO): DAT read: MF Frequency Band 2025-02-05T00:46:34.300Z,1738716394.300 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-02-05T00:46:34.300Z,1738716394.300 [DAT](INFO): DAT read: Feb 3 2025 18:58:29 2025-02-05T00:46:35.559Z,1738716395.559 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-02-05T00:46:35.560Z,1738716395.560 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-02-05T00:46:35.560Z,1738716395.560 [DAT](INFO): commRate: 800 2025-02-05T00:46:37.626Z,1738716397.626 [DAT](INFO): entering command mode 2025-02-05T00:46:37.826Z,1738716397.826 [DAT](INFO): DAT read: 2025-02-05T00:46:37.827Z,1738716397.827 [DAT](INFO): DAT read: user:1> 2025-02-05T00:46:37.827Z,1738716397.827 [DAT](INFO): setting verbose to 3 2025-02-05T00:46:38.079Z,1738716398.079 [DAT](INFO): DAT read: user:1> 2025-02-05T00:46:38.080Z,1738716398.080 [DAT](INFO): DAT read: Verbose | 3 2025-02-05T00:46:38.080Z,1738716398.080 [DAT](INFO): set verbose to 3 2025-02-05T00:46:38.080Z,1738716398.080 [DAT](INFO): setting DatVerbose to 27440 2025-02-05T00:46:38.330Z,1738716398.330 [DAT](INFO): DAT read: user:2> 2025-02-05T00:46:38.331Z,1738716398.331 [DAT](INFO): DAT read: DatVerbose | 27440 2025-02-05T00:46:38.332Z,1738716398.332 [DAT](INFO): set DatVerbose to 27440 2025-02-05T00:46:38.332Z,1738716398.332 [DAT](INFO): setting transmit power to 8 2025-02-05T00:46:38.583Z,1738716398.583 [DAT](INFO): DAT read: user:3> 2025-02-05T00:46:38.583Z,1738716398.583 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-02-05T00:46:38.584Z,1738716398.584 [DAT](INFO): set transmit power to 8 2025-02-05T00:46:38.584Z,1738716398.584 [DAT](INFO): setting local address to 1 2025-02-05T00:46:38.838Z,1738716398.838 [DAT](INFO): DAT read: user:4> 2025-02-05T00:46:38.839Z,1738716398.839 [DAT](INFO): DAT read: LocalAddr | 1 2025-02-05T00:46:38.839Z,1738716398.839 [DAT](INFO): set local address to 1 2025-02-05T00:46:38.840Z,1738716398.840 [DAT](INFO): Setting time to: 0:46:38 And date to:2/5/2025 2025-02-05T00:46:39.086Z,1738716399.086 [DAT](INFO): DAT read: user:5> 2025-02-05T00:46:39.087Z,1738716399.087 [DAT](INFO): DAT read: Wed Feb 5, 2025 00:46:38 2025-02-05T00:46:39.088Z,1738716399.088 [DAT](INFO): Local DAT time set to Wed Feb 5, 2025 00:46:38 2025-02-05T00:46:46.218Z,1738716406.218 [NAL9602](INFO): Powering up NAL9602 2025-02-05T00:47:06.781Z,1738716426.781 [NAL9602](ERROR): NAL9602 initialization error. 2025-02-05T00:47:06.781Z,1738716426.781 [NAL9602] Communications Fault, FailCount= 1 2025-02-05T00:47:06.781Z,1738716426.781 [NAL9602](ERROR): Communications Fault 2025-02-05T00:47:07.121Z,1738716427.121 [CBIT](ERROR): Communications Fault in component: NAL9602 2025-02-05T00:47:07.231Z,1738716427.231 [NAL9602](INFO): Powering down 2025-02-05T00:47:08.550Z,1738716428.550 [CBIT](INFO): Clearing failed state for component NAL9602 2025-02-05T00:47:08.550Z,1738716428.550 [NAL9602] No Fault, FailCount= 1 2025-02-05T00:47:18.317Z,1738716438.317 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, +0, +0, +0,V 2025-02-05T00:47:23.172Z,1738716443.172 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019562434, 0.0, +00,1475.0, 0 2025-02-05T00:47:29.683Z,1738716449.683 [SBIT](IMPORTANT): Beginning Startup BIT 2025-02-05T00:47:29.687Z,1738716449.687 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-05T00:47:31.224Z,1738716451.224 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:47:36.320Z,1738716456.320 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00, 00 2025-02-05T00:47:37.464Z,1738716457.464 [NAL9602](INFO): Powering up NAL9602 2025-02-05T00:47:40.849Z,1738716460.849 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.151366 CHAN A1 (24V): 0.225221 CHAN A2 (12V): 0.113026 CHAN A3 (5V): 0.041666 CHAN B0 (3.3V): 0.029548 CHAN B1 (3.15aV): 0.029164 CHAN B2 (3.15bV): 0.029909 CHAN B3 (GND): -0.003275 OPEN: 0.014528 Full Scale: +/- 1 mA 2025-02-05T00:47:43.907Z,1738716463.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:47:48.411Z,1738716468.411 [NAL9602](INFO): NAL9602 initialized 2025-02-05T00:47:48.423Z,1738716468.423 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, +0, +0, +0, 2025-02-05T00:47:49.528Z,1738716469.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:47:56.488Z,1738716476.488 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:48:03.876Z,1738716483.876 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, -151, +103, -40, 2025-02-05T00:48:17.176Z,1738716497.176 [NAL9602](INFO): SBD MO Status=2, MOMSN=21923, MT Status=2, MTMSN=0 2025-02-05T00:48:17.177Z,1738716497.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:48:20.662Z,1738716500.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:48:23.352Z,1738716503.352 [SBIT](IMPORTANT): SBIT PASSED 2025-02-05T00:48:23.352Z,1738716503.352 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-02-05T00:48:23.698Z,1738716503.698 [MissionManager](IMPORTANT): Started mission Startup 2025-02-05T00:48:23.698Z,1738716503.698 [Startup] Running Loop=1 2025-02-05T00:48:23.699Z,1738716503.699 [Startup](DEBUG): Aggregate::initialize Startup 2025-02-05T00:48:23.699Z,1738716503.699 [Startup:A.GoToSurface] Running Loop=1 2025-02-05T00:48:23.699Z,1738716503.699 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-05T00:48:23.699Z,1738716503.699 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-05T00:48:23.700Z,1738716503.700 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-05T00:48:23.700Z,1738716503.700 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-05T00:48:23.700Z,1738716503.700 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-05T00:48:23.701Z,1738716503.701 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-05T00:48:23.701Z,1738716503.701 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-05T00:48:23.703Z,1738716503.703 [Startup:StartupSatComms] Running Loop=1 2025-02-05T00:48:23.703Z,1738716503.703 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-02-05T00:48:23.704Z,1738716503.704 [Startup:StartupSatComms:A] Running Loop=1 2025-02-05T00:48:24.157Z,1738716504.157 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-02-05T00:48:34.153Z,1738716514.153 [NAL9602](INFO): SBD MO Status=2, MOMSN=21923, MT Status=2, MTMSN=0 2025-02-05T00:48:34.153Z,1738716514.153 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:48:35.821Z,1738716515.821 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, -162, -4, +0,A 2025-02-05T00:48:51.794Z,1738716531.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:48:53.921Z,1738716533.921 [NAL9602](INFO): SBD MO Status=2, MOMSN=21923, MT Status=2, MTMSN=0 2025-02-05T00:48:53.921Z,1738716533.921 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:49:07.779Z,1738716547.779 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0 +0.00, +0.00, 0.06, 0.00 2025-02-05T00:49:12.553Z,1738716552.553 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, -54, +3, +0,A 2025-02-05T00:49:14.541Z,1738716554.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=21923, MT Status=0, MTMSN=0 2025-02-05T00:49:14.541Z,1738716554.541 [NAL9602](INFO): No messages in MT queue 2025-02-05T00:49:18.303Z,1738716558.303 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-02-05T00:49:18.303Z,1738716558.303 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-02-05T00:49:18.355Z,1738716558.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-02-05T00:49:18.720Z,1738716558.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-02-05T00:49:18.721Z,1738716558.721 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-02-05T00:49:22.918Z,1738716562.918 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:49:23.840Z,1738716563.840 [Startup:StartupSatComms:A](INFO): Timed out from 2025-02-05T00:48:23.7Z 2025-02-05T00:49:23.840Z,1738716563.840 [Startup:StartupSatComms:A] Stopped 2025-02-05T00:49:23.841Z,1738716563.841 [Startup:StartupSatComms:B] Running Loop=1 2025-02-05T00:49:24.259Z,1738716564.259 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-05T00:49:27.501Z,1738716567.501 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019582866,.0, 0.0,1475.0, 0 2025-02-05T00:49:32.958Z,1738716572.958 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.911179 2025-02-05T00:50:04.095Z,1738716604.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:50:23.195Z,1738716623.195 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:50:24.161Z,1738716624.161 [Startup:StartupSatComms:B](INFO): Timed out from 2025-02-05T00:49:23.8Z 2025-02-05T00:50:24.161Z,1738716624.161 [Startup:StartupSatComms:B] Stopped 2025-02-05T00:50:24.162Z,1738716624.162 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-02-05T00:50:24.162Z,1738716624.162 [Startup:StartupSatComms] Stopped 2025-02-05T00:50:24.162Z,1738716624.162 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-02-05T00:50:24.163Z,1738716624.163 [Startup](INFO): Completed Startup 2025-02-05T00:50:24.163Z,1738716624.163 [MissionManager](INFO): Startup is completed. 2025-02-05T00:50:24.163Z,1738716624.163 [MissionManager](INFO): Uninitializing Mission Startup 2025-02-05T00:50:24.163Z,1738716624.163 [Startup] Stopped 2025-02-05T00:50:24.163Z,1738716624.163 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-02-05T00:50:24.163Z,1738716624.163 [Startup:A.GoToSurface] Stopped 2025-02-05T00:50:24.164Z,1738716624.164 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-05T00:50:24.484Z,1738716624.484 [MissionManager](IMPORTANT): Started mission Default 2025-02-05T00:50:24.485Z,1738716624.485 [Default] Running Loop=1 2025-02-05T00:50:24.485Z,1738716624.485 [Default](DEBUG): Aggregate::initialize Default 2025-02-05T00:50:24.485Z,1738716624.485 [Default:B.GoToSurface] Running Loop=1 2025-02-05T00:50:24.485Z,1738716624.485 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-05T00:50:24.485Z,1738716624.485 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-05T00:50:24.486Z,1738716624.486 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-05T00:50:24.486Z,1738716624.486 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-05T00:50:24.486Z,1738716624.486 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-05T00:50:24.487Z,1738716624.487 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-05T00:50:24.487Z,1738716624.487 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-05T00:50:24.487Z,1738716624.487 [Default:A.Wait] Running Loop=1 2025-02-05T00:50:24.487Z,1738716624.487 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-05T00:50:33.324Z,1738716633.324 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019593466, 0.0 0.0,147 2025-02-05T00:50:35.218Z,1738716635.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:50:35.720Z,1738716635.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:50:37.775Z,1738716637.775 [Default:A.Wait](INFO): Done Waiting. 2025-02-05T00:50:37.775Z,1738716637.775 [Default:A.Wait] Stopped 2025-02-05T00:50:37.775Z,1738716637.775 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-05T00:50:38.268Z,1738716638.268 [Default:CheckIn] Running Loop=1 2025-02-05T00:50:38.268Z,1738716638.268 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-05T00:50:38.269Z,1738716638.269 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-05T00:50:38.645Z,1738716638.645 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-05T00:50:39.381Z,1738716639.381 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019594050, 0.0, +.0,1475.0, 0 2025-02-05T00:50:48.242Z,1738716648.242 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:51:01.575Z,1738716661.575 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:51:06.343Z,1738716666.343 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:51:14.503Z,1738716674.503 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:51:27.437Z,1738716687.437 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:51:37.466Z,1738716697.466 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:51:39.959Z,1738716699.959 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:51:53.314Z,1738716713.314 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:52:05.411Z,1738716725.411 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:52:08.606Z,1738716728.606 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:52:18.414Z,1738716738.414 [Waterlinked](INFO): Powering down 2025-02-05T00:52:19.148Z,1738716739.148 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:52:19.186Z,1738716739.186 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-02-05T00:52:19.186Z,1738716739.186 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-02-05T00:52:19.237Z,1738716739.237 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-02-05T00:52:19.678Z,1738716739.678 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-02-05T00:52:19.678Z,1738716739.678 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-02-05T00:52:21.985Z,1738716741.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005220.00,A,3646.96048,N,12154.42781,W,0.894,37.02,050225,,,A*45 2025-02-05T00:52:21.988Z,1738716741.988 [NAL9602](INFO): GPS fix at 20250205T005220: (36.782675, -121.907130) 2025-02-05T00:52:22.018Z,1738716742.018 [Default:CheckIn:Read_GPS] Stopped 2025-02-05T00:52:22.018Z,1738716742.018 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-05T00:52:22.415Z,1738716742.415 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-05T00:52:32.080Z,1738716752.080 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:52:39.746Z,1738716759.746 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:52:51.171Z,1738716771.171 [NAL9602](INFO): SBD MO Status=2, MOMSN=21924, MT Status=2, MTMSN=0 2025-02-05T00:52:51.172Z,1738716771.172 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:53:10.874Z,1738716790.874 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:53:41.998Z,1738716821.998 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:53:44.955Z,1738716824.955 [NAL9602](INFO): SBD MO Status=1, MOMSN=21924, MT Status=0, MTMSN=0 2025-02-05T00:53:45.010Z,1738716825.010 [NAL9602](INFO): Sent 18 bytes from file Logs/20250204T154739/Courier0063.lzma 2025-02-05T00:53:45.010Z,1738716825.010 [NAL9602](INFO): Packets left to send: 0 2025-02-05T00:53:59.549Z,1738716839.549 [NAL9602](INFO): SBD MO Status=1, MOMSN=21925, MT Status=0, MTMSN=0 2025-02-05T00:53:59.606Z,1738716839.606 [NAL9602](INFO): Sent 151 bytes from file Logs/20250205T004606/Courier0000.lzma 2025-02-05T00:53:59.606Z,1738716839.606 [NAL9602](INFO): Packets left to send: 0 2025-02-05T00:54:13.130Z,1738716853.130 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:54:14.596Z,1738716854.596 [NAL9602](INFO): SBD MO Status=2, MOMSN=21926, MT Status=2, MTMSN=0 2025-02-05T00:54:14.596Z,1738716854.596 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:54:44.254Z,1738716884.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:54:46.939Z,1738716886.939 [NAL9602](INFO): SBD MO Status=2, MOMSN=21926, MT Status=2, MTMSN=0 2025-02-05T00:54:46.939Z,1738716886.939 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:55:03.983Z,1738716903.983 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:55:11.253Z,1738716911.253 [NAL9602](INFO): SBD MO Status=1, MOMSN=21926, MT Status=0, MTMSN=0 2025-02-05T00:55:11.314Z,1738716911.314 [NAL9602](INFO): Sent 222 bytes from file Logs/20250205T004606/Courier0004.lzma 2025-02-05T00:55:11.314Z,1738716911.314 [NAL9602](INFO): Packets left to send: 0 2025-02-05T00:55:15.382Z,1738716915.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:55:17.362Z,1738716917.362 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:55:31.084Z,1738716931.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:55:31.896Z,1738716931.896 [NAL9602](INFO): SBD MO Status=2, MOMSN=21927, MT Status=2, MTMSN=0 2025-02-05T00:55:31.896Z,1738716931.896 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:55:45.225Z,1738716945.225 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:55:46.510Z,1738716946.510 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:55:59.008Z,1738716959.008 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:56:03.060Z,1738716963.060 [NAL9602](INFO): SBD MO Status=2, MOMSN=21927, MT Status=2, MTMSN=0 2025-02-05T00:56:03.060Z,1738716963.060 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:56:13.206Z,1738716973.206 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:56:17.634Z,1738716977.634 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:56:21.694Z,1738716981.694 [NAL9602](INFO): SBD MO Status=2, MOMSN=21927, MT Status=2, MTMSN=0 2025-02-05T00:56:21.695Z,1738716981.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:56:26.938Z,1738716986.938 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:56:41.077Z,1738717001.077 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:56:48.758Z,1738717008.758 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:56:55.216Z,1738717015.216 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:57:11.051Z,1738717031.051 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T00:57:19.882Z,1738717039.882 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:57:27.212Z,1738717047.212 [NAL9602](INFO): SBD MO Status=1, MOMSN=21927, MT Status=0, MTMSN=0 2025-02-05T00:57:27.266Z,1738717047.266 [NAL9602](INFO): Sent 332 bytes from file Logs/20250204T154739/Express0061.lzma 2025-02-05T00:57:27.266Z,1738717047.266 [NAL9602](INFO): Packets left to send: 12 2025-02-05T00:57:33.210Z,1738717053.210 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003139 2025-02-05T00:57:42.205Z,1738717062.205 [NAL9602](INFO): SBD MO Status=2, MOMSN=21928, MT Status=2, MTMSN=0 2025-02-05T00:57:42.205Z,1738717062.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T00:58:01.799Z,1738717081.799 [DataOverHttps](INFO): Sending 11218 bytes from file Logs/20250204T154739/Express0061.lzma 2025-02-05T00:58:05.790Z,1738717085.790 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-02-05T00:58:12.907Z,1738717092.907 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-05T00:59:08.050Z,1738717148.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:59:39.178Z,1738717179.178 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T00:59:47.436Z,1738717187.436 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:00:02.789Z,1738717202.789 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:00:10.302Z,1738717210.302 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T01:00:17.737Z,1738717217.737 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:00:32.685Z,1738717232.685 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:00:33.394Z,1738717233.394 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.187378 2025-02-05T01:00:47.689Z,1738717247.689 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:00:59.812Z,1738717259.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=21928, MT Status=2, MTMSN=0 2025-02-05T01:00:59.812Z,1738717259.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T01:01:02.636Z,1738717262.636 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:01:04.526Z,1738717264.526 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T01:01:17.584Z,1738717277.584 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:01:35.401Z,1738717295.401 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:01:35.666Z,1738717295.666 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T01:01:49.144Z,1738717309.144 [NAL9602](INFO): SBD MO Status=2, MOMSN=21928, MT Status=2, MTMSN=0 2025-02-05T01:01:49.145Z,1738717309.145 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T01:01:50.771Z,1738717310.771 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:02:06.794Z,1738717326.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T01:02:16.265Z,1738717336.265 [NAL9602](INFO): SBD MO Status=1, MOMSN=21928, MT Status=0, MTMSN=0 2025-02-05T01:02:16.314Z,1738717336.314 [NAL9602](INFO): Sent 332 bytes from file Logs/20250204T154739/Express0061.lzma 2025-02-05T01:02:16.314Z,1738717336.314 [NAL9602](INFO): Packets left to send: 11 2025-02-05T01:02:37.934Z,1738717357.934 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T01:02:46.645Z,1738717366.645 [NAL9602](INFO): SBD MO Status=2, MOMSN=21929, MT Status=2, MTMSN=0 2025-02-05T01:02:46.645Z,1738717366.645 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T01:03:09.066Z,1738717389.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T01:03:10.104Z,1738717390.104 [NAL9602](INFO): SBD MO Status=2, MOMSN=21929, MT Status=2, MTMSN=0 2025-02-05T01:03:10.104Z,1738717390.104 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T01:03:40.198Z,1738717420.198 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-05T01:03:44.517Z,1738717424.517 [NAL9602](INFO): SBD MO Status=2, MOMSN=21929, MT Status=2, MTMSN=0 2025-02-05T01:03:44.517Z,1738717424.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T01:03:51.614Z,1738717431.614 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003162 2025-02-05T01:04:02.245Z,1738717442.245 [DataOverHttps](INFO): Sending 11218 bytes from file Logs/20250204T154739/Express0061.lzma 2025-02-05T01:04:09.755Z,1738717449.755 [CommandExec](IMPORTANT): got command gfscan 2025-02-05T01:04:10.048Z,1738717450.048 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-05T01:04:10.827Z,1738717450.827 [NAL9602](INFO): SBD MO Status=2, MOMSN=21929, MT Status=2, MTMSN=0 2025-02-05T01:04:10.828Z,1738717450.828 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-05T01:04:20.953Z,1738717460.953 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003186 CHAN A1 (24V): 0.007302 CHAN A2 (12V): 0.003309 CHAN A3 (5V): -0.000024 CHAN B0 (3.3V): 0.001992 CHAN B1 (3.15aV): 0.000819 CHAN B2 (3.15bV): 0.000593 CHAN B3 (GND): -0.000357 OPEN: 0.009417 Full Scale: +/- 1 mA 2025-02-05T01:04:23.306Z,1738717463.306 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-02-05T01:04:30.621Z,1738717470.621 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:04:41.530Z,1738717481.530 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-05T01:04:44.760Z,1738717484.760 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:04:58.952Z,1738717498.952 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:05:07.232Z,1738717507.232 [DataOverHttps](INFO): Sending 11218 bytes from file Logs/20250204T154739/Express0061.lzma 2025-02-05T01:05:13.089Z,1738717513.089 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:05:21.268Z,1738717521.268 [DataOverHttps](INFO): Moved sent file to Logs/20250204T154739/Express0061.lzma.bak 2025-02-05T01:05:21.268Z,1738717521.268 [DataOverHttps](INFO): SBD MOMSN=23956621 2025-02-05T01:05:27.242Z,1738717527.242 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:05:35.175Z,1738717535.175 [CommandExec](IMPORTANT): got command gfscan 2025-02-05T01:05:35.351Z,1738717535.351 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-05T01:05:40.965Z,1738717540.965 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:05:41.305Z,1738717541.305 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250204T154739/Express0064.lzma 2025-02-05T01:05:43.312Z,1738717543.312 [DataOverHttps](INFO): Moved sent file to Logs/20250204T154739/Express0064.lzma.bak 2025-02-05T01:05:43.312Z,1738717543.312 [DataOverHttps](INFO): SBD MOMSN=23956656 2025-02-05T01:05:46.243Z,1738717546.243 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.195045 CHAN A1 (24V): 0.255980 CHAN A2 (12V): 0.157315 CHAN A3 (5V): 0.078989 CHAN B0 (3.3V): 0.077361 CHAN B1 (3.15aV): 0.080939 CHAN B2 (3.15bV): 0.074871 CHAN B3 (GND): 0.051845 OPEN: 0.027894 Full Scale: +/- 1 mA 2025-02-05T01:05:54.700Z,1738717554.700 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:06:07.566Z,1738717567.566 [DataOverHttps](INFO): Sending 702 bytes from file Logs/20250205T004606/Express0001.lzma 2025-02-05T01:06:08.438Z,1738717568.438 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:06:12.584Z,1738717572.584 [DataOverHttps](INFO): Moved sent file to Logs/20250205T004606/Express0001.lzma.bak 2025-02-05T01:06:12.584Z,1738717572.584 [DataOverHttps](INFO): SBD MOMSN=23956658 2025-02-05T01:06:21.768Z,1738717581.768 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:06:34.089Z,1738717594.089 [CommandExec](IMPORTANT): got command configSet WetLabsBB2FL.loadAtStartup 0 bool persist 2025-02-05T01:06:34.089Z,1738717594.089 [CommandExec](IMPORTANT): configSet WetLabsBB2FL.loadAtStartup requires a restart to take effect. 2025-02-05T01:06:34.090Z,1738717594.090 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown. 2025-02-05T01:06:34.450Z,1738717594.450 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20250205T004606/Express0005.lzma 2025-02-05T01:06:35.452Z,1738717595.452 [DataOverHttps](INFO): Moved sent file to Logs/20250205T004606/Express0005.lzma.bak 2025-02-05T01:06:35.452Z,1738717595.452 [DataOverHttps](INFO): SBD MOMSN=23956672 2025-02-05T01:06:35.505Z,1738717595.505 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:06:36.765Z,1738717596.765 [Default:CheckIn:Read_Iridium] Stopped 2025-02-05T01:06:36.765Z,1738717596.765 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-05T01:06:36.765Z,1738717596.765 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-05T01:07:01.331Z,1738717621.331 [CommandExec](IMPORTANT): got command gfscan 2025-02-05T01:07:01.445Z,1738717621.445 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-05T01:07:12.317Z,1738717632.317 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.204380 CHAN A1 (24V): 0.309262 CHAN A2 (12V): 0.185103 CHAN A3 (5V): 0.104429 CHAN B0 (3.3V): 0.093821 CHAN B1 (3.15aV): 0.095636 CHAN B2 (3.15bV): 0.093443 CHAN B3 (GND): 0.059337 OPEN: 0.028253 Full Scale: +/- 1 mA 2025-02-05T01:07:24.492Z,1738717644.492 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadC2 2025-02-05T01:07:37.558Z,1738717657.558 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-02-05T01:07:41.703Z,1738717661.703 [CommandExec](IMPORTANT): got command gfscan 2025-02-05T01:07:41.796Z,1738717661.796 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-05T01:07:52.701Z,1738717672.701 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003424 CHAN A1 (24V): 0.007457 CHAN A2 (12V): 0.003936 CHAN A3 (5V): 0.000021 CHAN B0 (3.3V): 0.001605 CHAN B1 (3.15aV): 0.000852 CHAN B2 (3.15bV): 0.001201 CHAN B3 (GND): -0.000307 OPEN: 0.009391 Full Scale: +/- 1 mA 2025-02-05T01:08:14.187Z,1738717694.187 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadC6 2025-02-05T01:08:20.979Z,1738717700.979 [CommandExec](IMPORTANT): got command gfscan 2025-02-05T01:08:20.987Z,1738717700.987 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-05T01:08:31.888Z,1738717711.888 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003752 CHAN A1 (24V): 0.007204 CHAN A2 (12V): 0.003188 CHAN A3 (5V): 0.000071 CHAN B0 (3.3V): 0.001353 CHAN B1 (3.15aV): 0.000425 CHAN B2 (3.15bV): 0.000124 CHAN B3 (GND): -0.000471 OPEN: 0.009150 Full Scale: +/- 1 mA 2025-02-05T01:09:11.058Z,1738717751.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:09:26.007Z,1738717766.007 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:09:41.357Z,1738717781.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:09:57.113Z,1738717797.113 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:10:12.464Z,1738717812.464 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:10:28.220Z,1738717828.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:10:43.168Z,1738717843.168 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:10:49.999Z,1738717849.999 [DataOverHttps](IMPORTANT): SBD MTMSN=20250205T011049 2025-02-05T01:10:58.168Z,1738717858.168 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-05T01:11:00.106Z,1738717860.106 [DataOverHttps](INFO): Received command: restart sys 2025-02-05T01:11:00.160Z,1738717860.160 [CommandExec](IMPORTANT): got command restart system 2025-02-05T01:11:02.234Z,1738717862.234 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-02-05T01:11:02.234Z,1738717862.234 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:02.234Z,1738717862.234 [CommandExec](INFO): Uninitializing the command executive. 2025-02-05T01:11:02.235Z,1738717862.235 [CommandExec](INFO): Uninitializing the command scheduler. 2025-02-05T01:11:02.235Z,1738717862.235 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:02.418Z,1738717862.418 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-02-05T01:11:02.418Z,1738717862.418 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-02-05T01:11:02.418Z,1738717862.418 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:02.419Z,1738717862.419 [NavChartDb](INFO): Join timeout helper Thread ID is 7916 2025-02-05T01:11:02.462Z,1738717862.462 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:02.462Z,1738717862.462 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:02.480Z,1738717862.480 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-02-05T01:11:02.480Z,1738717862.480 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:02.480Z,1738717862.480 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7918 2025-02-05T01:11:02.958Z,1738717862.958 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:02.958Z,1738717862.958 [WetLabsBB2FL](INFO): Powering down 2025-02-05T01:11:02.959Z,1738717862.959 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:02.970Z,1738717862.970 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-02-05T01:11:02.970Z,1738717862.970 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:02.970Z,1738717862.970 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7919 2025-02-05T01:11:03.274Z,1738717863.274 [CTD_Seabird](INFO): Powering down 2025-02-05T01:11:03.286Z,1738717863.286 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:03.286Z,1738717863.286 [CTD_Seabird](INFO): Powering down 2025-02-05T01:11:03.298Z,1738717863.298 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:03.310Z,1738717863.310 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-02-05T01:11:03.310Z,1738717863.310 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:03.310Z,1738717863.310 [Radio_Surface](INFO): Join timeout helper Thread ID is 7920 2025-02-05T01:11:03.650Z,1738717863.650 [Radio_Surface](INFO): Powering down 2025-02-05T01:11:03.651Z,1738717863.651 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:03.651Z,1738717863.651 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:03.658Z,1738717863.658 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-02-05T01:11:03.658Z,1738717863.658 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:03.658Z,1738717863.658 [Onboard](INFO): Join timeout helper Thread ID is 7921 2025-02-05T01:11:04.122Z,1738717864.122 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-05T01:11:04.226Z,1738717864.226 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:04.226Z,1738717864.226 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:04.241Z,1738717864.241 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-02-05T01:11:04.242Z,1738717864.242 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:04.242Z,1738717864.242 [DataOverHttps](INFO): Join timeout helper Thread ID is 7922 2025-02-05T01:11:05.126Z,1738717865.126 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:05.126Z,1738717865.126 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.146Z,1738717865.146 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-02-05T01:11:05.147Z,1738717865.147 [DAT ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.147Z,1738717865.147 [DAT](INFO): Join timeout helper Thread ID is 7923 2025-02-05T01:11:05.386Z,1738717865.386 [DAT](INFO): Powering down 2025-02-05T01:11:05.459Z,1738717865.459 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:05.459Z,1738717865.459 [DAT](INFO): Powering down 2025-02-05T01:11:05.460Z,1738717865.460 [DAT ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.483Z,1738717865.483 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-02-05T01:11:05.483Z,1738717865.483 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.483Z,1738717865.483 [BackseatComponent](INFO): Join timeout helper Thread ID is 7924 2025-02-05T01:11:05.538Z,1738717865.538 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:05.538Z,1738717865.538 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.551Z,1738717865.551 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-02-05T01:11:05.551Z,1738717865.551 [logger ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.551Z,1738717865.551 [logger](INFO): Join timeout helper Thread ID is 7925 2025-02-05T01:11:05.566Z,1738717865.566 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:05.566Z,1738717865.566 [logger ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.570Z,1738717865.570 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-02-05T01:11:05.571Z,1738717865.571 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.571Z,1738717865.571 [CommandLine](INFO): Join timeout helper Thread ID is 7926 2025-02-05T01:11:05.590Z,1738717865.590 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:05.590Z,1738717865.590 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.610Z,1738717865.610 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-02-05T01:11:05.611Z,1738717865.611 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.611Z,1738717865.611 [CommandExec](INFO): Join timeout helper Thread ID is 7927 2025-02-05T01:11:05.612Z,1738717865.612 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-02-05T01:11:05.612Z,1738717865.612 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:05.613Z,1738717865.613 [controlThread](INFO): Join timeout helper Thread ID is 7928 2025-02-05T01:11:05.842Z,1738717865.842 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-05T01:11:05.842Z,1738717865.842 [controlThread](DEBUG): Uninitializing ControlThread 2025-02-05T01:11:05.843Z,1738717865.843 [AHRS_M2](INFO): Powering down 2025-02-05T01:11:05.915Z,1738717865.915 [NAL9602](INFO): Powering down 2025-02-05T01:11:05.916Z,1738717865.916 [Sonardyne_Nano](INFO): Powering down 2025-02-05T01:11:06.034Z,1738717866.034 [Waterlinked](INFO): Powering down 2025-02-05T01:11:06.037Z,1738717866.037 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-02-05T01:11:06.038Z,1738717866.038 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-02-05T01:11:06.038Z,1738717866.038 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-02-05T01:11:06.039Z,1738717866.039 [MissionManager](INFO): Uninitializing Mission Default 2025-02-05T01:11:06.039Z,1738717866.039 [Default] Stopped 2025-02-05T01:11:06.039Z,1738717866.039 [Default](DEBUG): Aggregate::uninitialize Default 2025-02-05T01:11:06.039Z,1738717866.039 [Default:B.GoToSurface] Stopped 2025-02-05T01:11:06.039Z,1738717866.039 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-05T01:11:06.039Z,1738717866.039 [Default:CheckIn] Stopped 2025-02-05T01:11:06.039Z,1738717866.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-05T01:11:06.039Z,1738717866.039 [Default:CheckIn:C.Wait] Stopped 2025-02-05T01:11:06.039Z,1738717866.039 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-05T01:11:06.042Z,1738717866.042 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-02-05T01:11:06.043Z,1738717866.043 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-02-05T01:11:06.043Z,1738717866.043 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-02-05T01:11:06.043Z,1738717866.043 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-02-05T01:11:06.044Z,1738717866.044 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-02-05T01:11:06.044Z,1738717866.044 [BuoyancyServo](INFO): Powering down 2025-02-05T01:11:06.058Z,1738717866.058 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-02-05T01:11:06.058Z,1738717866.058 [ElevatorServo](INFO): Powering down 2025-02-05T01:11:06.059Z,1738717866.059 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-02-05T01:11:06.059Z,1738717866.059 [MassServo](INFO): Powering down 2025-02-05T01:11:06.060Z,1738717866.060 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-02-05T01:11:06.060Z,1738717866.060 [RudderServo](INFO): Powering down 2025-02-05T01:11:06.061Z,1738717866.061 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-02-05T01:11:06.061Z,1738717866.061 [ThrusterHE](INFO): Powering down 2025-02-05T01:11:06.062Z,1738717866.062 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-02-05T01:11:06.063Z,1738717866.063 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-02-05T01:11:06.063Z,1738717866.063 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-02-05T01:11:06.063Z,1738717866.063 [CBIT](DEBUG): Powering off loads. 2025-02-05T01:11:06.074Z,1738717866.074 [CBIT](DEBUG): Disabling WDT. 2025-02-05T01:11:06.086Z,1738717866.086 [CBIT](DEBUG): Opening all GF detection circuits. 2025-02-05T01:11:06.087Z,1738717866.087 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.115Z,1738717866.115 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.117Z,1738717866.117 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.124Z,1738717866.124 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.130Z,1738717866.130 [DAT ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.174Z,1738717866.174 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.266Z,1738717866.266 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.273Z,1738717866.273 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.281Z,1738717866.281 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-05T01:11:06.355Z,1738717866.355 [logger ThreadHandler](INFO): Thread cancelled.