2025-12-15T18:18:06.795Z,1765822686.795 [Supervisor](DEBUG): Initializing supervisor. 2025-12-15T18:18:06.800Z,1765822686.800 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-12-15T18:18:06.800Z,1765822686.800 [SyncHandler](INFO): Protected caller Thread ID is 706 2025-12-15T18:18:06.801Z,1765822686.801 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-12-15T18:18:06.802Z,1765822686.802 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-12-15T18:18:06.802Z,1765822686.802 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 707 2025-12-15T18:18:06.806Z,1765822686.806 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-12-15T18:18:06.825Z,1765822686.825 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-12-15T18:18:06.826Z,1765822686.826 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-12-15T18:18:06.827Z,1765822686.827 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 708 2025-12-15T18:18:06.831Z,1765822686.831 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-12-15T18:18:06.832Z,1765822686.832 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-12-15T18:18:06.832Z,1765822686.832 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 709 2025-12-15T18:18:06.834Z,1765822686.834 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-12-15T18:18:06.835Z,1765822686.835 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-12-15T18:18:06.835Z,1765822686.835 [logger ThreadHandler](INFO): Protected caller Thread ID is 710 2025-12-15T18:18:06.839Z,1765822686.839 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-12-15T18:18:06.839Z,1765822686.839 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-12-15T18:18:06.841Z,1765822686.841 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-12-15T18:18:07.385Z,1765822687.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-12-15T18:18:07.385Z,1765822687.385 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-12-15T18:18:07.602Z,1765822687.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-12-15T18:18:07.602Z,1765822687.602 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-12-15T18:18:07.704Z,1765822687.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-12-15T18:18:07.704Z,1765822687.704 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-12-15T18:18:07.943Z,1765822687.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-12-15T18:18:07.944Z,1765822687.944 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-12-15T18:18:08.034Z,1765822688.034 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-12-15T18:18:08.141Z,1765822688.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-12-15T18:18:08.142Z,1765822688.142 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-12-15T18:18:08.513Z,1765822688.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-12-15T18:18:08.513Z,1765822688.513 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-12-15T18:18:08.655Z,1765822688.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-12-15T18:18:08.656Z,1765822688.656 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-12-15T18:18:09.198Z,1765822689.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-12-15T18:18:09.199Z,1765822689.199 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-12-15T18:18:09.386Z,1765822689.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-12-15T18:18:09.387Z,1765822689.387 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-12-15T18:18:09.809Z,1765822689.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-12-15T18:18:09.809Z,1765822689.809 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-12-15T18:18:10.104Z,1765822690.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-12-15T18:18:10.105Z,1765822690.105 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-12-15T18:18:10.225Z,1765822690.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-12-15T18:18:10.226Z,1765822690.226 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-12-15T18:18:11.055Z,1765822691.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-12-15T18:18:11.056Z,1765822691.056 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-12-15T18:18:11.403Z,1765822691.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-12-15T18:18:11.403Z,1765822691.403 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-12-15T18:18:11.652Z,1765822691.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-12-15T18:18:11.654Z,1765822691.654 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2025-12-15T18:18:11.655Z,1765822691.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2025-12-15T18:18:11.793Z,1765822691.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2025-12-15T18:18:12.318Z,1765822692.318 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2025-12-15T18:18:13.001Z,1765822693.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2025-12-15T18:18:13.098Z,1765822693.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2025-12-15T18:18:13.371Z,1765822693.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-12-15T18:18:13.371Z,1765822693.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2025-12-15T18:18:13.485Z,1765822693.485 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2025-12-15T18:18:13.602Z,1765822693.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2025-12-15T18:18:13.711Z,1765822693.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2025-12-15T18:18:13.871Z,1765822693.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2025-12-15T18:18:13.978Z,1765822693.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2025-12-15T18:18:14.076Z,1765822694.076 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-12-15T18:18:14.084Z,1765822694.084 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-12-15T18:18:14.095Z,1765822694.095 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-12-15T18:18:14.096Z,1765822694.096 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-12-15T18:18:14.227Z,1765822694.227 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-12-15T18:18:14.228Z,1765822694.228 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-12-15T18:18:14.280Z,1765822694.280 [VerticalControl](DEBUG): Construct VerticalControl. 2025-12-15T18:18:14.339Z,1765822694.339 [VerticalControl] Loaded 2025-12-15T18:18:14.339Z,1765822694.339 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-12-15T18:18:14.342Z,1765822694.342 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-12-15T18:18:14.388Z,1765822694.388 [HorizontalControl] Loaded 2025-12-15T18:18:14.388Z,1765822694.388 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-12-15T18:18:14.390Z,1765822694.390 [SpeedControl](DEBUG): Construct SpeedControl. 2025-12-15T18:18:14.393Z,1765822694.393 [SpeedControl] Loaded 2025-12-15T18:18:14.393Z,1765822694.393 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-12-15T18:18:14.396Z,1765822694.396 [LoopControl](DEBUG): Construct LoopControl. 2025-12-15T18:18:14.397Z,1765822694.397 [LoopControl] Loaded 2025-12-15T18:18:14.397Z,1765822694.397 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-12-15T18:18:14.397Z,1765822694.397 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-12-15T18:18:14.398Z,1765822694.398 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-12-15T18:18:14.531Z,1765822694.531 [BuoyancyServo] Loaded 2025-12-15T18:18:14.531Z,1765822694.531 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-12-15T18:18:14.553Z,1765822694.553 [ElevatorServo] Loaded 2025-12-15T18:18:14.553Z,1765822694.553 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-12-15T18:18:14.573Z,1765822694.573 [MassServo] Loaded 2025-12-15T18:18:14.574Z,1765822694.574 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-12-15T18:18:14.593Z,1765822694.593 [RudderServo] Loaded 2025-12-15T18:18:14.593Z,1765822694.593 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-12-15T18:18:14.610Z,1765822694.610 [ThrusterHE] Loaded 2025-12-15T18:18:14.610Z,1765822694.610 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-12-15T18:18:14.610Z,1765822694.610 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-12-15T18:18:14.611Z,1765822694.611 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-12-15T18:18:14.662Z,1765822694.662 [DepthRateCalculator] Loaded 2025-12-15T18:18:14.662Z,1765822694.662 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-12-15T18:18:14.667Z,1765822694.667 [PitchRateCalculator] Loaded 2025-12-15T18:18:14.667Z,1765822694.667 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-12-15T18:18:14.676Z,1765822694.676 [SpeedCalculator] Loaded 2025-12-15T18:18:14.677Z,1765822694.677 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-12-15T18:18:14.681Z,1765822694.681 [YawRateCalculator] Loaded 2025-12-15T18:18:14.681Z,1765822694.681 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-12-15T18:18:14.699Z,1765822694.699 [ElevatorOffsetCalculator] Loaded 2025-12-15T18:18:14.699Z,1765822694.699 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-12-15T18:18:14.699Z,1765822694.699 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-12-15T18:18:14.700Z,1765822694.700 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-12-15T18:18:14.814Z,1765822694.814 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-12-15T18:18:14.814Z,1765822694.814 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-12-15T18:18:14.827Z,1765822694.827 [NavChart] Loaded 2025-12-15T18:18:14.827Z,1765822694.827 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-12-15T18:18:14.833Z,1765822694.833 [UniversalFixResidualReporter] Loaded 2025-12-15T18:18:14.833Z,1765822694.833 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-12-15T18:18:14.834Z,1765822694.834 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-12-15T18:18:14.834Z,1765822694.834 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-12-15T18:18:15.452Z,1765822695.452 [AHRS_M2] Loaded 2025-12-15T18:18:15.452Z,1765822695.452 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-12-15T18:18:15.705Z,1765822695.705 [BackseatComponent] Loaded 2025-12-15T18:18:15.705Z,1765822695.705 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-12-15T18:18:15.706Z,1765822695.706 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4092B4E0 2025-12-15T18:18:15.707Z,1765822695.707 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 798 2025-12-15T18:18:15.710Z,1765822695.710 [LcmPublisher] Loaded 2025-12-15T18:18:15.710Z,1765822695.710 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2025-12-15T18:18:15.716Z,1765822695.716 [BioacousticsDataBridge] Loaded 2025-12-15T18:18:15.717Z,1765822695.717 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2025-12-15T18:18:16.539Z,1765822696.539 [BPC1] Loaded 2025-12-15T18:18:16.539Z,1765822696.539 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-12-15T18:18:16.637Z,1765822696.637 [DAT] Loaded 2025-12-15T18:18:16.638Z,1765822696.638 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-12-15T18:18:16.639Z,1765822696.639 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4095B4E0 2025-12-15T18:18:16.639Z,1765822696.639 [DAT ThreadHandler](INFO): Protected caller Thread ID is 799 2025-12-15T18:18:16.943Z,1765822696.943 [DataOverHttps] Loaded 2025-12-15T18:18:16.944Z,1765822696.944 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-12-15T18:18:16.945Z,1765822696.945 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0 2025-12-15T18:18:16.945Z,1765822696.945 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 800 2025-12-15T18:18:16.968Z,1765822696.968 [Depth_Keller] Loaded 2025-12-15T18:18:16.968Z,1765822696.968 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-12-15T18:18:16.973Z,1765822696.973 [DropWeight] Loaded 2025-12-15T18:18:16.974Z,1765822696.974 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-12-15T18:18:17.036Z,1765822697.036 [NAL9602] Loaded 2025-12-15T18:18:17.037Z,1765822697.037 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-12-15T18:18:17.066Z,1765822697.066 [Onboard] Loaded 2025-12-15T18:18:17.066Z,1765822697.066 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-12-15T18:18:17.067Z,1765822697.067 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409BB4E0 2025-12-15T18:18:17.068Z,1765822697.068 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 801 2025-12-15T18:18:17.082Z,1765822697.082 [Power24vConverter] Loaded 2025-12-15T18:18:17.082Z,1765822697.082 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-12-15T18:18:17.099Z,1765822697.099 [Radio_Surface] Loaded 2025-12-15T18:18:17.099Z,1765822697.099 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-12-15T18:18:17.100Z,1765822697.100 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409EB4E0 2025-12-15T18:18:17.101Z,1765822697.101 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 802 2025-12-15T18:18:17.114Z,1765822697.114 [Sonardyne_Nano] Loaded 2025-12-15T18:18:17.114Z,1765822697.114 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-12-15T18:18:17.115Z,1765822697.115 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-12-15T18:18:17.115Z,1765822697.115 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-12-15T18:18:17.338Z,1765822697.338 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-12-15T18:18:17.338Z,1765822697.338 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-12-15T18:18:17.692Z,1765822697.692 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-12-15T18:18:17.692Z,1765822697.692 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-12-15T18:18:17.723Z,1765822697.723 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-12-15T18:18:17.723Z,1765822697.723 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-12-15T18:18:17.895Z,1765822697.895 [CTD_Seabird] Loaded 2025-12-15T18:18:17.896Z,1765822697.896 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-12-15T18:18:17.897Z,1765822697.897 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B344E0 2025-12-15T18:18:17.897Z,1765822697.897 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 804 2025-12-15T18:18:17.919Z,1765822697.919 [PAR_Licor] Loaded 2025-12-15T18:18:17.919Z,1765822697.919 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-12-15T18:18:17.962Z,1765822697.962 [WetLabsBB2FL] Loaded 2025-12-15T18:18:17.963Z,1765822697.963 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-12-15T18:18:17.963Z,1765822697.963 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0 2025-12-15T18:18:17.964Z,1765822697.964 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 805 2025-12-15T18:18:17.965Z,1765822697.965 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-12-15T18:18:17.965Z,1765822697.965 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-12-15T18:18:18.300Z,1765822698.300 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-12-15T18:18:18.301Z,1765822698.301 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-12-15T18:18:18.457Z,1765822698.457 [SBIT](DEBUG): Construct Startup Built In Test. 2025-12-15T18:18:18.466Z,1765822698.466 [SBIT] Loaded 2025-12-15T18:18:18.466Z,1765822698.466 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-12-15T18:18:18.469Z,1765822698.469 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-12-15T18:18:18.481Z,1765822698.481 [IBIT] Loaded 2025-12-15T18:18:18.482Z,1765822698.482 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-12-15T18:18:18.487Z,1765822698.487 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-12-15T18:18:18.565Z,1765822698.565 [CBIT] Loaded 2025-12-15T18:18:18.565Z,1765822698.565 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-12-15T18:18:18.597Z,1765822698.597 [GFScanner] Loaded 2025-12-15T18:18:18.597Z,1765822698.597 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-12-15T18:18:18.597Z,1765822698.597 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-12-15T18:18:18.603Z,1765822698.603 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-12-15T18:18:18.607Z,1765822698.607 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-12-15T18:18:18.618Z,1765822698.618 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-12-15T18:18:18.619Z,1765822698.619 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 2025-12-15T18:18:18.619Z,1765822698.619 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 806 2025-12-15T18:18:18.623Z,1765822698.623 [Supervisor](INFO): Main Thread ID is 705 2025-12-15T18:18:18.623Z,1765822698.623 [Supervisor](DEBUG): Running supervisor. 2025-12-15T18:18:18.624Z,1765822698.624 [CommandExec ThreadHandler](INFO): Handler Thread ID is 807 2025-12-15T18:18:18.625Z,1765822698.625 [CommandExec](INFO): Initializing the command executive. 2025-12-15T18:18:18.627Z,1765822698.627 [CommandLine ThreadHandler](INFO): Handler Thread ID is 808 2025-12-15T18:18:18.629Z,1765822698.629 [controlThread ThreadHandler](INFO): Handler Thread ID is 809 2025-12-15T18:18:18.629Z,1765822698.629 [controlThread](DEBUG): Initializing ControlThread 2025-12-15T18:18:18.630Z,1765822698.630 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-12-15T18:18:18.632Z,1765822698.632 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-12-15T18:18:18.632Z,1765822698.632 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-12-15T18:18:18.633Z,1765822698.633 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-12-15T18:18:18.634Z,1765822698.634 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-12-15T18:18:18.635Z,1765822698.635 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-12-15T18:18:18.635Z,1765822698.635 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-12-15T18:18:18.635Z,1765822698.635 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-12-15T18:18:18.636Z,1765822698.636 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-12-15T18:18:18.637Z,1765822698.637 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-12-15T18:18:18.637Z,1765822698.637 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-12-15T18:18:18.645Z,1765822698.645 [SBIT](INFO): Initialize SBIT Component. 2025-12-15T18:18:18.645Z,1765822698.645 [SBIT](IMPORTANT): git: 2025-12-15_A 2025-12-15T18:18:18.645Z,1765822698.645 [SBIT](INFO): git hash: 5b50703234a74cb9c609bca727303ff504a0b330 2025-12-15T18:18:18.646Z,1765822698.646 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-12-15T18:18:18.647Z,1765822698.647 [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-12-15T18:18:18.648Z,1765822698.648 [SBIT](INFO): Beginning SBIT in 40.000000 seconds. 2025-12-15T18:18:18.649Z,1765822698.649 [IBIT](INFO): Initialize IBIT Component. 2025-12-15T18:18:18.649Z,1765822698.649 [CBIT](DEBUG): Initialize CBIT Component. 2025-12-15T18:18:18.650Z,1765822698.650 [logger ThreadHandler](INFO): Handler Thread ID is 810 2025-12-15T18:18:18.660Z,1765822698.660 [CBIT](DEBUG): Initialized mux pins. 2025-12-15T18:18:18.660Z,1765822698.660 [CBIT](DEBUG): Initializing the watchdog timer. 2025-12-15T18:18:18.669Z,1765822698.669 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 811 2025-12-15T18:18:18.681Z,1765822698.681 [DAT ThreadHandler](INFO): Handler Thread ID is 812 2025-12-15T18:18:18.681Z,1765822698.681 [DAT](INFO): Powering up 2025-12-15T18:18:18.682Z,1765822698.682 [DAT](DEBUG): Initializing DAT. 2025-12-15T18:18:18.684Z,1765822698.684 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-12-15T18:18:18.685Z,1765822698.685 [CBIT](DEBUG): Initializing heartbeat. 2025-12-15T18:18:18.687Z,1765822698.687 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 814 2025-12-15T18:18:18.688Z,1765822698.688 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-12-15T18:18:18.697Z,1765822698.697 [Onboard ThreadHandler](INFO): Handler Thread ID is 815 2025-12-15T18:18:18.714Z,1765822698.714 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 816 2025-12-15T18:18:18.741Z,1765822698.741 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 817 2025-12-15T18:18:18.741Z,1765822698.741 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-12-15T18:18:18.746Z,1765822698.746 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 820 2025-12-15T18:18:18.749Z,1765822698.749 [WetLabsBB2FL](INFO): Powering up 2025-12-15T18:18:18.750Z,1765822698.750 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 822 2025-12-15T18:18:18.756Z,1765822698.756 [CBIT](DEBUG): Deactivating emergency mode. 2025-12-15T18:18:18.759Z,1765822698.759 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000 2025-12-15T18:18:18.759Z,1765822698.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000 2025-12-15T18:18:18.759Z,1765822698.759 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000 2025-12-15T18:18:18.759Z,1765822698.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000 2025-12-15T18:18:18.760Z,1765822698.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000 2025-12-15T18:18:18.760Z,1765822698.760 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000 2025-12-15T18:18:18.760Z,1765822698.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000 2025-12-15T18:18:18.760Z,1765822698.760 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000 2025-12-15T18:18:18.760Z,1765822698.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000 2025-12-15T18:18:18.761Z,1765822698.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000 2025-12-15T18:18:18.761Z,1765822698.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000 2025-12-15T18:18:18.761Z,1765822698.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000 2025-12-15T18:18:18.761Z,1765822698.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000 2025-12-15T18:18:18.761Z,1765822698.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000 2025-12-15T18:18:18.761Z,1765822698.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000 2025-12-15T18:18:18.762Z,1765822698.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000 2025-12-15T18:18:18.762Z,1765822698.762 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000 2025-12-15T18:18:18.762Z,1765822698.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000 2025-12-15T18:18:18.762Z,1765822698.762 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000 2025-12-15T18:18:18.762Z,1765822698.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000 2025-12-15T18:18:18.796Z,1765822698.796 [CBIT](DEBUG): Backplane powered. 2025-12-15T18:18:18.797Z,1765822698.797 [GFScanner](DEBUG): Initializing GFScanner 2025-12-15T18:18:18.797Z,1765822698.797 [GFScanner](DEBUG): Deactivating GF circuits. 2025-12-15T18:18:18.801Z,1765822698.801 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-12-15T18:18:18.802Z,1765822698.802 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-12-15T18:18:18.803Z,1765822698.803 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-12-15T18:18:18.806Z,1765822698.806 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-12-15T18:18:18.840Z,1765822698.840 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-12-15T18:18:18.873Z,1765822698.873 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-15T18:18:18.887Z,1765822698.887 [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-12-15T18:18:18.887Z,1765822698.887 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-12-15T18:18:18.887Z,1765822698.887 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-12-15T18:18:18.905Z,1765822698.905 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-12-15T18:18:19.120Z,1765822699.120 [Radio_Surface](INFO): Powering up 2025-12-15T18:18:19.198Z,1765822699.198 [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-12-15T18:18:19.242Z,1765822699.242 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-12-15T18:18:19.244Z,1765822699.244 [Default:A.Wait](DEBUG): Construct Wait. 2025-12-15T18:18:19.251Z,1765822699.251 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-15T18:18:19.297Z,1765822699.297 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-12-15T18:18:19.299Z,1765822699.299 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-12-15T18:18:19.329Z,1765822699.329 [Default:E.Execute](DEBUG): Construct Execute. 2025-12-15T18:18:19.332Z,1765822699.332 [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-12-15T18:18:19.337Z,1765822699.337 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter, 2025-12-15T18:18:19.365Z,1765822699.365 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-12-15T18:18:19.425Z,1765822699.425 [Depth_Keller](INFO): Initializing. 2025-12-15T18:18:19.426Z,1765822699.426 [Power24vConverter](INFO): Powering up. 2025-12-15T18:18:19.427Z,1765822699.427 [Sonardyne_Nano](INFO): Initializing. 2025-12-15T18:18:19.465Z,1765822699.465 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-12-15T18:18:19.531Z,1765822699.531 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-12-15T18:18:19.537Z,1765822699.537 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-12-15T18:18:19.538Z,1765822699.538 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-12-15T18:18:19.545Z,1765822699.545 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-12-15T18:18:19.546Z,1765822699.546 [MassServo](DEBUG): Initializing EZServoServo. 2025-12-15T18:18:19.553Z,1765822699.553 [MassServo](DEBUG): Initializing MassServo. 2025-12-15T18:18:19.553Z,1765822699.553 [RudderServo](DEBUG): Initializing EZServoServo. 2025-12-15T18:18:19.561Z,1765822699.561 [RudderServo](DEBUG): Initializing RudderServo. 2025-12-15T18:18:19.562Z,1765822699.562 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-12-15T18:18:19.569Z,1765822699.569 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-12-15T18:18:19.780Z,1765822699.780 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-12-15T18:18:19.780Z,1765822699.780 [DropWeight] Hardware Fault, FailCount= 1 2025-12-15T18:18:19.780Z,1765822699.780 [DropWeight](ERROR): Hardware Fault 2025-12-15T18:18:19.812Z,1765822699.812 [CommandExec](FAULT): Scheduling is paused 2025-12-15T18:18:19.820Z,1765822699.820 [CBIT](INFO): Critical error at 20251215T181819 2025-12-15T18:18:19.821Z,1765822699.821 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-12-15T18:18:19.823Z,1765822699.823 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-12-15T18:18:19.824Z,1765822699.824 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-12-15T18:18:20.174Z,1765822700.174 [CBIT](INFO): Critical error at 20251215T181819 2025-12-15T18:18:21.365Z,1765822701.365 [WetLabsBB2FL](INFO): Powering down 2025-12-15T18:18:21.368Z,1765822701.368 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-12-15T18:18:22.657Z,1765822702.657 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-12-15T18:18:23.008Z,1765822703.008 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-15T18:18:25.226Z,1765822705.226 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-12-15T18:18:25.773Z,1765822705.773 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-12-15T18:18:31.041Z,1765822711.041 [DAT](INFO): DAT read: 2025-12-15T18:18:31.042Z,1765822711.042 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-15T18:18:31.987Z,1765822711.987 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004641 2025-12-15T18:18:32.805Z,1765822712.805 [DAT](INFO): DAT read: MF Frequency Band 2025-12-15T18:18:32.806Z,1765822712.806 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-15T18:18:32.807Z,1765822712.807 [DAT](INFO): DAT read: Nov 21 2025 21:31:10 2025-12-15T18:18:33.820Z,1765822713.820 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-15T18:18:33.821Z,1765822713.821 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-12-15T18:18:33.821Z,1765822713.821 [DAT](INFO): commRate: 800 2025-12-15T18:18:35.880Z,1765822715.880 [DAT](INFO): entering command mode 2025-12-15T18:18:36.081Z,1765822716.081 [DAT](INFO): DAT read: 2025-12-15T18:18:36.082Z,1765822716.082 [DAT](INFO): DAT read: user:1> 2025-12-15T18:18:36.082Z,1765822716.082 [DAT](INFO): setting verbose to 3 2025-12-15T18:18:36.333Z,1765822716.333 [DAT](INFO): DAT read: user:1> 2025-12-15T18:18:36.334Z,1765822716.334 [DAT](INFO): DAT read: Verbose | 3 2025-12-15T18:18:36.334Z,1765822716.334 [DAT](INFO): set verbose to 3 2025-12-15T18:18:36.335Z,1765822716.335 [DAT](INFO): setting DatVerbose to 27440 2025-12-15T18:18:36.585Z,1765822716.585 [DAT](INFO): DAT read: user:2> 2025-12-15T18:18:36.586Z,1765822716.586 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-15T18:18:36.586Z,1765822716.586 [DAT](INFO): set DatVerbose to 27440 2025-12-15T18:18:36.587Z,1765822716.587 [DAT](INFO): setting transmit power to 8 2025-12-15T18:18:36.837Z,1765822716.837 [DAT](INFO): DAT read: user:3> 2025-12-15T18:18:36.838Z,1765822716.838 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-15T18:18:36.838Z,1765822716.838 [DAT](INFO): set transmit power to 8 2025-12-15T18:18:36.839Z,1765822716.839 [DAT](INFO): setting local address to 12 2025-12-15T18:18:37.089Z,1765822717.089 [DAT](INFO): DAT read: user:4> 2025-12-15T18:18:37.090Z,1765822717.090 [DAT](INFO): DAT read: LocalAddr | 12 2025-12-15T18:18:37.090Z,1765822717.090 [DAT](INFO): set local address to 12 2025-12-15T18:18:37.091Z,1765822717.091 [DAT](INFO): Setting time to: 18:18:37 And date to:12/15/2025 2025-12-15T18:18:37.341Z,1765822717.341 [DAT](INFO): DAT read: user:5> 2025-12-15T18:18:37.342Z,1765822717.342 [DAT](INFO): DAT read: Mon Dec 15, 2025 18:18:37 2025-12-15T18:18:37.342Z,1765822717.342 [DAT](INFO): Local DAT time set to Mon Dec 15, 2025 18:18:37 2025-12-15T18:18:47.230Z,1765822727.230 [NAL9602](INFO): Powering up NAL9602 2025-12-15T18:18:58.150Z,1765822738.150 [NAL9602](INFO): NAL9602 initialized 2025-12-15T18:18:59.398Z,1765822739.398 [SBIT](IMPORTANT): Beginning Startup BIT 2025-12-15T18:18:59.403Z,1765822739.403 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-12-15T18:19:03.885Z,1765822743.885 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005971 CHAN A1 (24V): -0.005606 CHAN A2 (12V): -0.003705 CHAN A3 (5V): -0.001993 CHAN B0 (3.3V): -0.000554 CHAN B1 (3.15aV): -0.001376 CHAN B2 (3.15bV): -0.001314 CHAN B3 (GND): -0.000233 OPEN: 0.004679 Full Scale: +/- 1 mA 2025-12-15T18:19:53.635Z,1765822793.635 [SBIT](IMPORTANT): SBIT PASSED 2025-12-15T18:19:53.636Z,1765822793.636 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-12-15T18:19:53.637Z,1765822793.637 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-12-15T18:19:53.637Z,1765822793.637 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter; 2025-12-15T18:19:53.637Z,1765822793.637 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 1.000000 count_per_second; 2025-12-15T18:19:53.637Z,1765822793.637 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter; 2025-12-15T18:19:53.637Z,1765822793.637 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=200.117527 cubic_centimeter; 2025-12-15T18:19:53.637Z,1765822793.637 [SBIT](IMPORTANT): VerticalControl.massDefault=5.027315 millimeter; 2025-12-15T18:19:53.637Z,1765822793.637 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2025-12-15T18:19:53.996Z,1765822793.996 [MissionManager](IMPORTANT): Started mission Startup 2025-12-15T18:19:53.996Z,1765822793.996 [Startup] Running Loop=1 2025-12-15T18:19:53.996Z,1765822793.996 [Startup](DEBUG): Aggregate::initialize Startup 2025-12-15T18:19:53.996Z,1765822793.996 [Startup:A.GoToSurface] Running Loop=1 2025-12-15T18:19:53.996Z,1765822793.996 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-15T18:19:53.997Z,1765822793.997 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-15T18:19:53.997Z,1765822793.997 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-15T18:19:53.998Z,1765822793.998 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-15T18:19:53.998Z,1765822793.998 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-15T18:19:53.998Z,1765822793.998 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-15T18:19:53.999Z,1765822793.999 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-15T18:19:53.000Z,1765822794.000 [Startup:StartupSatComms] Running Loop=1 2025-12-15T18:19:54.000Z,1765822794.000 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-12-15T18:19:54.000Z,1765822794.000 [Startup:StartupSatComms:A] Running Loop=1 2025-12-15T18:19:54.399Z,1765822794.399 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-12-15T18:20:54.146Z,1765822854.146 [Startup:StartupSatComms:A](INFO): Timed out from 2025-12-15T18:19:54.0Z 2025-12-15T18:20:54.146Z,1765822854.146 [Startup:StartupSatComms:A] Stopped 2025-12-15T18:20:54.146Z,1765822854.146 [Startup:StartupSatComms:B] Running Loop=1 2025-12-15T18:20:54.550Z,1765822854.550 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-15T18:20:58.666Z,1765822858.666 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:21:01.089Z,1765822861.089 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251121T193208/Courier0060.lzma 2025-12-15T18:21:02.090Z,1765822862.090 [DataOverHttps](INFO): Moved sent file to Logs/20251121T193208/Courier0060.lzma.bak 2025-12-15T18:21:02.091Z,1765822862.091 [DataOverHttps](INFO): SBD MOMSN=26497874 2025-12-15T18:21:09.704Z,1765822869.704 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:21:18.890Z,1765822878.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-12-15T18:21:18.890Z,1765822878.890 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:21:18.941Z,1765822878.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:21:19.322Z,1765822879.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:21:19.322Z,1765822879.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-12-15T18:21:20.568Z,1765822880.568 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:21:21.576Z,1765822881.576 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20251215T181806/Courier0000.lzma 2025-12-15T18:21:22.578Z,1765822882.578 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Courier0000.lzma.bak 2025-12-15T18:21:22.579Z,1765822882.579 [DataOverHttps](INFO): SBD MOMSN=26497899 2025-12-15T18:21:29.791Z,1765822889.791 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T18:21:31.422Z,1765822891.422 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:21:38.129Z,1765822898.129 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20251121T193208/Express0061.lzma 2025-12-15T18:21:39.130Z,1765822899.130 [DataOverHttps](INFO): Moved sent file to Logs/20251121T193208/Express0061.lzma.bak 2025-12-15T18:21:39.131Z,1765822899.131 [DataOverHttps](INFO): SBD MOMSN=26497904 2025-12-15T18:21:42.383Z,1765822902.383 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:21:53.272Z,1765822913.272 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:21:54.408Z,1765822914.408 [Startup:StartupSatComms:B](INFO): Timed out from 2025-12-15T18:20:54.1Z 2025-12-15T18:21:54.408Z,1765822914.408 [Startup:StartupSatComms:B] Stopped 2025-12-15T18:21:54.408Z,1765822914.408 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-12-15T18:21:54.408Z,1765822914.408 [Startup:StartupSatComms] Stopped 2025-12-15T18:21:54.408Z,1765822914.408 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-12-15T18:21:54.409Z,1765822914.409 [Startup](INFO): Completed Startup 2025-12-15T18:21:54.409Z,1765822914.409 [MissionManager](INFO): Startup is completed. 2025-12-15T18:21:54.409Z,1765822914.409 [MissionManager](INFO): Uninitializing Mission Startup 2025-12-15T18:21:54.410Z,1765822914.410 [Startup] Stopped 2025-12-15T18:21:54.410Z,1765822914.410 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-12-15T18:21:54.410Z,1765822914.410 [Startup:A.GoToSurface] Stopped 2025-12-15T18:21:54.410Z,1765822914.410 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-15T18:21:54.801Z,1765822914.801 [MissionManager](IMPORTANT): Started mission Default 2025-12-15T18:21:54.801Z,1765822914.801 [Default] Running Loop=1 2025-12-15T18:21:54.802Z,1765822914.802 [Default](DEBUG): Aggregate::initialize Default 2025-12-15T18:21:54.802Z,1765822914.802 [Default:B.GoToSurface] Running Loop=1 2025-12-15T18:21:54.802Z,1765822914.802 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-15T18:21:54.802Z,1765822914.802 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-15T18:21:54.802Z,1765822914.802 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-15T18:21:54.803Z,1765822914.803 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-15T18:21:54.803Z,1765822914.803 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-15T18:21:54.803Z,1765822914.803 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-15T18:21:54.803Z,1765822914.803 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-15T18:21:54.804Z,1765822914.804 [Default:A.Wait] Running Loop=1 2025-12-15T18:21:54.804Z,1765822914.804 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-12-15T18:21:55.033Z,1765822915.033 [DataOverHttps](INFO): Sending 908 bytes from file Logs/20251215T181806/Express0001.lzma 2025-12-15T18:21:56.034Z,1765822916.034 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Express0001.lzma.bak 2025-12-15T18:21:56.034Z,1765822916.034 [DataOverHttps](INFO): SBD MOMSN=26497910 2025-12-15T18:22:04.148Z,1765822924.148 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:22:08.136Z,1765822928.136 [Default:A.Wait](INFO): Done Waiting. 2025-12-15T18:22:08.136Z,1765822928.136 [Default:A.Wait] Stopped 2025-12-15T18:22:08.136Z,1765822928.136 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T18:22:08.550Z,1765822928.550 [Default:CheckIn] Running Loop=1 2025-12-15T18:22:08.550Z,1765822928.550 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T18:22:08.550Z,1765822928.550 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T18:22:08.971Z,1765822928.971 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-12-15T18:22:14.637Z,1765822934.637 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T18:22:15.069Z,1765822935.069 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:22:25.999Z,1765822945.999 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:22:36.918Z,1765822956.918 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:22:42.514Z,1765822962.514 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T18:22:48.185Z,1765822968.185 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:22:59.094Z,1765822979.094 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:23:02.258Z,1765822982.258 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:23:12.758Z,1765822992.758 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-12-15T18:23:12.760Z,1765822992.760 [BPC1](ERROR): BPC1B failed to parse battery stick message. 2025-12-15T18:23:20.030Z,1765823000.030 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-15T18:23:20.838Z,1765823000.838 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:23:35.020Z,1765823015.020 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:23:37.806Z,1765823017.806 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:23:51.196Z,1765823031.196 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T18:23:53.177Z,1765823033.177 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-15T18:24:01.670Z,1765823041.670 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-15T18:24:08.126Z,1765823048.126 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:24:10.955Z,1765823050.955 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-15T18:24:19.861Z,1765823059.861 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-12-15T18:24:19.862Z,1765823059.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:24:19.872Z,1765823059.872 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:24:20.286Z,1765823060.286 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:24:20.286Z,1765823060.286 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-12-15T18:24:25.094Z,1765823065.094 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:24:29.542Z,1765823069.542 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-15T18:24:42.062Z,1765823082.062 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:24:46.914Z,1765823086.914 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-15T18:24:58.222Z,1765823098.222 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:25:04.686Z,1765823104.686 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-15T18:25:15.594Z,1765823115.594 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:25:22.461Z,1765823122.461 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-15T18:25:32.157Z,1765823132.157 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-15T18:25:39.885Z,1765823139.885 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-15T18:25:53.587Z,1765823153.587 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5. 2025-12-15T18:25:53.590Z,1765823153.590 [BPC1](INFO): Received data from all battery sticks. 2025-12-15T18:27:08.754Z,1765823228.754 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-12-15T18:22:08.6Z 2025-12-15T18:27:08.754Z,1765823228.754 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T18:27:08.754Z,1765823228.754 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T18:27:09.134Z,1765823229.134 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-15T18:27:16.304Z,1765823236.304 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20251215T181806/Courier0004.lzma 2025-12-15T18:27:17.370Z,1765823237.370 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Courier0004.lzma.bak 2025-12-15T18:27:17.370Z,1765823237.370 [DataOverHttps](INFO): SBD MOMSN=26497993 2025-12-15T18:27:20.861Z,1765823240.861 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-12-15T18:27:20.861Z,1765823240.861 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:27:20.899Z,1765823240.899 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:27:21.284Z,1765823241.284 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:27:21.285Z,1765823241.285 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-12-15T18:27:32.900Z,1765823252.900 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20251215T181806/Express0005.lzma 2025-12-15T18:27:33.902Z,1765823253.902 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Express0005.lzma.bak 2025-12-15T18:27:33.902Z,1765823253.902 [DataOverHttps](INFO): SBD MOMSN=26497998 2025-12-15T18:27:35.418Z,1765823255.418 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T18:27:35.418Z,1765823255.418 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T18:27:35.418Z,1765823255.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T18:28:23.464Z,1765823303.464 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-15T18:28:59.014Z,1765823339.014 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-12-15T18:28:59.014Z,1765823339.014 [NAL9602] Data Fault, FailCount= 1 2025-12-15T18:28:59.014Z,1765823339.014 [NAL9602](ERROR): Data Fault 2025-12-15T18:28:59.031Z,1765823339.031 [CBIT](ERROR): Data Fault in component: NAL9602 2025-12-15T18:28:59.414Z,1765823339.414 [NAL9602](INFO): Powering down 2025-12-15T18:29:00.254Z,1765823340.254 [CBIT](INFO): Clearing failed state for component NAL9602 2025-12-15T18:29:00.254Z,1765823340.254 [NAL9602] No Fault, FailCount= 1 2025-12-15T18:29:29.718Z,1765823369.718 [NAL9602](INFO): Powering up NAL9602 2025-12-15T18:29:40.631Z,1765823380.631 [NAL9602](INFO): NAL9602 initialized 2025-12-15T18:30:21.841Z,1765823421.841 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-12-15T18:30:21.841Z,1765823421.841 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:30:21.851Z,1765823421.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:30:22.264Z,1765823422.264 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:30:22.264Z,1765823422.264 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-12-15T18:32:35.970Z,1765823555.970 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T18:32:35.970Z,1765823555.970 [Default:CheckIn:C.Wait] Stopped 2025-12-15T18:32:35.970Z,1765823555.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T18:32:35.970Z,1765823555.970 [Default:CheckIn:D] Running Loop=1 2025-12-15T18:32:36.401Z,1765823556.401 [Default:CheckIn:D] Stopped 2025-12-15T18:32:36.401Z,1765823556.401 [Default:CheckIn:E] Running Loop=1 2025-12-15T18:32:36.799Z,1765823556.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.693323 min 2025-12-15T18:32:36.799Z,1765823556.799 [Default:CheckIn:E] Stopped 2025-12-15T18:32:36.799Z,1765823556.799 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T18:32:36.799Z,1765823556.799 [Default:CheckIn] Stopped 2025-12-15T18:32:36.799Z,1765823556.799 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T18:32:36.799Z,1765823556.799 [Default:CheckIn](INFO): Running loop #2 2025-12-15T18:32:36.800Z,1765823556.800 [Default:CheckIn] Running Loop=2 2025-12-15T18:32:36.800Z,1765823556.800 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T18:32:36.800Z,1765823556.800 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T18:33:20.039Z,1765823600.039 [CBIT](INFO): Clearing failed state for component DropWeight 2025-12-15T18:33:20.039Z,1765823600.039 [DropWeight] No Fault, FailCount= 1 2025-12-15T18:33:22.853Z,1765823602.853 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-12-15T18:33:22.854Z,1765823602.854 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:33:22.864Z,1765823602.864 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:33:23.265Z,1765823603.265 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:33:23.265Z,1765823603.265 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-12-15T18:36:23.837Z,1765823783.837 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-12-15T18:36:23.837Z,1765823783.837 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:36:23.879Z,1765823783.879 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:36:24.259Z,1765823784.259 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:36:24.259Z,1765823784.259 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-12-15T18:37:36.975Z,1765823856.975 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-12-15T18:32:36.8Z 2025-12-15T18:37:36.975Z,1765823856.975 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T18:37:36.975Z,1765823856.975 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T18:37:43.080Z,1765823863.080 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251215T181806/Courier0007.lzma 2025-12-15T18:37:44.083Z,1765823864.083 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Courier0007.lzma.bak 2025-12-15T18:37:44.083Z,1765823864.083 [DataOverHttps](INFO): SBD MOMSN=26498128 2025-12-15T18:38:00.292Z,1765823880.292 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20251215T181806/Express0008.lzma 2025-12-15T18:38:01.296Z,1765823881.296 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Express0008.lzma.bak 2025-12-15T18:38:01.296Z,1765823881.296 [DataOverHttps](INFO): SBD MOMSN=26498133 2025-12-15T18:38:02.850Z,1765823882.850 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T18:38:02.850Z,1765823882.850 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T18:38:02.851Z,1765823882.851 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T18:38:23.846Z,1765823903.846 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-15T18:39:24.837Z,1765823964.837 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-12-15T18:39:24.837Z,1765823964.837 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:39:24.869Z,1765823964.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:39:25.280Z,1765823965.280 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:39:25.280Z,1765823965.280 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-12-15T18:39:43.407Z,1765823983.407 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-12-15T18:39:43.407Z,1765823983.407 [NAL9602] Data Fault, FailCount= 2 2025-12-15T18:39:43.407Z,1765823983.407 [NAL9602](ERROR): Data Fault 2025-12-15T18:39:43.445Z,1765823983.445 [CBIT](ERROR): Data Fault in component: NAL9602 2025-12-15T18:39:43.818Z,1765823983.818 [NAL9602](INFO): Powering down 2025-12-15T18:39:44.648Z,1765823984.648 [CBIT](INFO): Clearing failed state for component NAL9602 2025-12-15T18:39:44.649Z,1765823984.649 [NAL9602] No Fault, FailCount= 2 2025-12-15T18:40:14.118Z,1765824014.118 [NAL9602](INFO): Powering up NAL9602 2025-12-15T18:40:25.026Z,1765824025.026 [NAL9602](INFO): NAL9602 initialized 2025-12-15T18:42:25.845Z,1765824145.845 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-12-15T18:42:25.846Z,1765824145.846 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:42:25.856Z,1765824145.856 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:42:26.263Z,1765824146.263 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:42:26.263Z,1765824146.263 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2025-12-15T18:43:03.422Z,1765824183.422 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T18:43:03.423Z,1765824183.423 [Default:CheckIn:C.Wait] Stopped 2025-12-15T18:43:03.423Z,1765824183.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T18:43:03.423Z,1765824183.423 [Default:CheckIn:D] Running Loop=1 2025-12-15T18:43:03.807Z,1765824183.807 [Default:CheckIn:D] Stopped 2025-12-15T18:43:03.807Z,1765824183.807 [Default:CheckIn:E] Running Loop=1 2025-12-15T18:43:04.220Z,1765824184.220 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.150094 min 2025-12-15T18:43:04.220Z,1765824184.220 [Default:CheckIn:E] Stopped 2025-12-15T18:43:04.221Z,1765824184.221 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T18:43:04.221Z,1765824184.221 [Default:CheckIn] Stopped 2025-12-15T18:43:04.221Z,1765824184.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T18:43:04.221Z,1765824184.221 [Default:CheckIn](INFO): Running loop #3 2025-12-15T18:43:04.221Z,1765824184.221 [Default:CheckIn] Running Loop=3 2025-12-15T18:43:04.221Z,1765824184.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T18:43:04.221Z,1765824184.221 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T18:45:26.843Z,1765824326.843 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2025-12-15T18:45:26.844Z,1765824326.844 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:45:26.855Z,1765824326.855 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:45:27.270Z,1765824327.270 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:45:27.270Z,1765824327.270 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2025-12-15T18:48:04.382Z,1765824484.382 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-12-15T18:43:04.2Z 2025-12-15T18:48:04.382Z,1765824484.382 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T18:48:04.382Z,1765824484.382 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T18:48:10.188Z,1765824490.188 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251215T181806/Courier0010.lzma 2025-12-15T18:48:11.190Z,1765824491.190 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Courier0010.lzma.bak 2025-12-15T18:48:11.190Z,1765824491.190 [DataOverHttps](INFO): SBD MOMSN=26498177 2025-12-15T18:48:24.168Z,1765824504.168 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-15T18:48:26.900Z,1765824506.900 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20251215T181806/Express0011.lzma 2025-12-15T18:48:27.835Z,1765824507.835 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2025-12-15T18:48:27.836Z,1765824507.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:48:27.846Z,1765824507.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:48:27.902Z,1765824507.902 [DataOverHttps](INFO): Moved sent file to Logs/20251215T181806/Express0011.lzma.bak 2025-12-15T18:48:27.902Z,1765824507.902 [DataOverHttps](INFO): SBD MOMSN=26498179 2025-12-15T18:48:28.261Z,1765824508.261 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:48:28.261Z,1765824508.261 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2025-12-15T18:48:29.427Z,1765824509.427 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T18:48:29.427Z,1765824509.427 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T18:48:29.427Z,1765824509.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T18:50:10.053Z,1765824610.053 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-12-15T18:50:12.061Z,1765824612.061 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.507038 2025-12-15T18:50:22.365Z,1765824622.365 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003306 2025-12-15T18:50:26.576Z,1765824626.576 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-12-15T18:50:26.576Z,1765824626.576 [NAL9602] Data Fault, FailCount= 3 2025-12-15T18:50:26.576Z,1765824626.576 [NAL9602](ERROR): Data Fault 2025-12-15T18:50:26.609Z,1765824626.609 [CBIT](ERROR): Data Fault in component: NAL9602 2025-12-15T18:50:26.987Z,1765824626.987 [NAL9602](INFO): Powering down 2025-12-15T18:50:27.848Z,1765824627.848 [CBIT](INFO): Clearing failed state for component NAL9602 2025-12-15T18:50:27.848Z,1765824627.848 [NAL9602] No Fault, FailCount= 3 2025-12-15T18:50:57.286Z,1765824657.286 [NAL9602](INFO): Powering up NAL9602 2025-12-15T18:51:08.194Z,1765824668.194 [NAL9602](INFO): NAL9602 initialized 2025-12-15T18:51:28.821Z,1765824688.821 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2025-12-15T18:51:28.821Z,1765824688.821 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:51:28.851Z,1765824688.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:51:29.238Z,1765824689.238 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:51:29.239Z,1765824689.239 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2025-12-15T18:53:30.015Z,1765824810.015 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T18:53:30.015Z,1765824810.015 [Default:CheckIn:C.Wait] Stopped 2025-12-15T18:53:30.015Z,1765824810.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T18:53:30.015Z,1765824810.015 [Default:CheckIn:D] Running Loop=1 2025-12-15T18:53:30.422Z,1765824810.422 [Default:CheckIn:D] Stopped 2025-12-15T18:53:30.422Z,1765824810.422 [Default:CheckIn:E] Running Loop=1 2025-12-15T18:53:30.827Z,1765824810.827 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.593669 min 2025-12-15T18:53:30.827Z,1765824810.827 [Default:CheckIn:E] Stopped 2025-12-15T18:53:30.827Z,1765824810.827 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T18:53:30.827Z,1765824810.827 [Default:CheckIn] Stopped 2025-12-15T18:53:30.827Z,1765824810.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T18:53:30.827Z,1765824810.827 [Default:CheckIn](INFO): Running loop #4 2025-12-15T18:53:30.827Z,1765824810.827 [Default:CheckIn] Running Loop=4 2025-12-15T18:53:30.828Z,1765824810.828 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T18:53:30.828Z,1765824810.828 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T18:54:29.811Z,1765824869.811 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2025-12-15T18:54:29.811Z,1765824869.811 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-15T18:54:29.821Z,1765824869.821 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-15T18:54:30.228Z,1765824870.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-15T18:54:30.229Z,1765824870.229 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2025-12-15T18:56:58.590Z,1765825018.590 [CommandExec](IMPORTANT): got command restart system 2025-12-15T18:57:00.651Z,1765825020.651 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T18:57:00.651Z,1765825020.651 [CommandExec](INFO): Uninitializing the command executive. 2025-12-15T18:57:00.652Z,1765825020.652 [CommandExec](INFO): Uninitializing the command scheduler. 2025-12-15T18:57:00.652Z,1765825020.652 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:00.792Z,1765825020.792 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-12-15T18:57:00.793Z,1765825020.793 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-12-15T18:57:00.793Z,1765825020.793 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:00.793Z,1765825020.793 [NavChartDb](INFO): Join timeout helper Thread ID is 872 2025-12-15T18:57:01.133Z,1765825021.133 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T18:57:01.133Z,1765825021.133 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.136Z,1765825021.136 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-12-15T18:57:01.136Z,1765825021.136 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.137Z,1765825021.137 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 873 2025-12-15T18:57:01.305Z,1765825021.305 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T18:57:01.305Z,1765825021.305 [WetLabsBB2FL](INFO): Powering down 2025-12-15T18:57:01.306Z,1765825021.306 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.320Z,1765825021.320 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-12-15T18:57:01.321Z,1765825021.321 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.321Z,1765825021.321 [CTD_Seabird](INFO): Join timeout helper Thread ID is 874 2025-12-15T18:57:01.401Z,1765825021.401 [CTD_Seabird](INFO): Powering down 2025-12-15T18:57:01.413Z,1765825021.413 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T18:57:01.413Z,1765825021.413 [CTD_Seabird](INFO): Powering down 2025-12-15T18:57:01.424Z,1765825021.424 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.425Z,1765825021.425 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-12-15T18:57:01.425Z,1765825021.425 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.425Z,1765825021.425 [Radio_Surface](INFO): Join timeout helper Thread ID is 875 2025-12-15T18:57:01.816Z,1765825021.816 [Radio_Surface](INFO): Powering down 2025-12-15T18:57:01.817Z,1765825021.817 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T18:57:01.818Z,1765825021.818 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.832Z,1765825021.832 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-12-15T18:57:01.832Z,1765825021.832 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-15T18:57:01.833Z,1765825021.833 [Onboard](INFO): Join timeout helper Thread ID is 876 2025-12-15T18:57:01.889Z,1765825021.889 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-15T18:57:05.629Z,1765825025.629 [Onboard ThreadHandler](INFO): Uninitializi