2025-07-08T16:28:26.650Z,1751992106.650 [Supervisor](DEBUG): Initializing supervisor. 2025-07-08T16:28:26.654Z,1751992106.654 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-07-08T16:28:26.655Z,1751992106.655 [SyncHandler](INFO): Protected caller Thread ID is 837 2025-07-08T16:28:26.655Z,1751992106.655 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-07-08T16:28:26.656Z,1751992106.656 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-07-08T16:28:26.656Z,1751992106.656 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2025-07-08T16:28:26.661Z,1751992106.661 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-07-08T16:28:26.681Z,1751992106.681 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-07-08T16:28:26.682Z,1751992106.682 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-07-08T16:28:26.683Z,1751992106.683 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2025-07-08T16:28:26.687Z,1751992106.687 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-07-08T16:28:26.688Z,1751992106.688 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-07-08T16:28:26.689Z,1751992106.689 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2025-07-08T16:28:26.691Z,1751992106.691 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-07-08T16:28:26.692Z,1751992106.692 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-07-08T16:28:26.692Z,1751992106.692 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2025-07-08T16:28:26.696Z,1751992106.696 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-07-08T16:28:26.697Z,1751992106.697 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-07-08T16:28:26.699Z,1751992106.699 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-07-08T16:28:27.052Z,1751992107.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-07-08T16:28:27.054Z,1751992107.054 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-07-08T16:28:27.155Z,1751992107.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-07-08T16:28:27.157Z,1751992107.157 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-07-08T16:28:27.275Z,1751992107.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-07-08T16:28:27.277Z,1751992107.277 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-07-08T16:28:27.419Z,1751992107.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-07-08T16:28:27.421Z,1751992107.421 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-07-08T16:28:27.800Z,1751992107.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-07-08T16:28:27.800Z,1751992107.800 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-07-08T16:28:27.907Z,1751992107.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-07-08T16:28:27.908Z,1751992107.908 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-07-08T16:28:28.340Z,1751992108.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-07-08T16:28:28.340Z,1751992108.340 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-07-08T16:28:28.584Z,1751992108.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-07-08T16:28:28.586Z,1751992108.586 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-07-08T16:28:28.675Z,1751992108.675 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-07-08T16:28:28.945Z,1751992108.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-07-08T16:28:28.947Z,1751992108.947 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-07-08T16:28:29.184Z,1751992109.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-07-08T16:28:29.186Z,1751992109.186 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-07-08T16:28:29.763Z,1751992109.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-07-08T16:28:29.764Z,1751992109.764 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-07-08T16:28:29.965Z,1751992109.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-07-08T16:28:29.967Z,1751992109.967 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-07-08T16:28:30.274Z,1751992110.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-07-08T16:28:30.275Z,1751992110.275 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-07-08T16:28:31.150Z,1751992111.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-07-08T16:28:31.152Z,1751992111.152 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-07-08T16:28:31.752Z,1751992111.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-07-08T16:28:31.754Z,1751992111.754 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-07-08T16:28:31.756Z,1751992111.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-07-08T16:28:31.864Z,1751992111.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-07-08T16:28:32.006Z,1751992112.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-07-08T16:28:32.099Z,1751992112.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-07-08T16:28:32.216Z,1751992112.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-07-08T16:28:32.313Z,1751992112.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-07-08T16:28:32.591Z,1751992112.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-07-08T16:28:32.593Z,1751992112.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-07-08T16:28:32.713Z,1751992112.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-07-08T16:28:32.821Z,1751992112.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-07-08T16:28:32.973Z,1751992112.973 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-07-08T16:28:33.069Z,1751992113.069 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-07-08T16:28:33.082Z,1751992113.082 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-07-08T16:28:33.256Z,1751992113.256 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-07-08T16:28:33.256Z,1751992113.256 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-07-08T16:28:33.329Z,1751992113.329 [VerticalControl](DEBUG): Construct VerticalControl. 2025-07-08T16:28:33.393Z,1751992113.393 [VerticalControl] Loaded 2025-07-08T16:28:33.393Z,1751992113.393 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-07-08T16:28:33.396Z,1751992113.396 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-07-08T16:28:33.445Z,1751992113.445 [HorizontalControl] Loaded 2025-07-08T16:28:33.445Z,1751992113.445 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-07-08T16:28:33.448Z,1751992113.448 [SpeedControl](DEBUG): Construct SpeedControl. 2025-07-08T16:28:33.451Z,1751992113.451 [SpeedControl] Loaded 2025-07-08T16:28:33.451Z,1751992113.451 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-07-08T16:28:33.454Z,1751992113.454 [LoopControl](DEBUG): Construct LoopControl. 2025-07-08T16:28:33.454Z,1751992113.454 [LoopControl] Loaded 2025-07-08T16:28:33.455Z,1751992113.455 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-07-08T16:28:33.455Z,1751992113.455 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-07-08T16:28:33.456Z,1751992113.456 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-07-08T16:28:33.544Z,1751992113.544 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-07-08T16:28:33.544Z,1751992113.544 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-07-08T16:28:34.402Z,1751992114.402 [AHRS_M2] Loaded 2025-07-08T16:28:34.403Z,1751992114.403 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-07-08T16:28:34.657Z,1751992114.657 [BackseatComponent] Loaded 2025-07-08T16:28:34.657Z,1751992114.657 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-07-08T16:28:34.658Z,1751992114.658 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-07-08T16:28:34.659Z,1751992114.659 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920 2025-07-08T16:28:34.662Z,1751992114.662 [LcmUniversalReporter] Loaded 2025-07-08T16:28:34.662Z,1751992114.662 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-07-08T16:28:35.506Z,1751992115.506 [BPC1] Loaded 2025-07-08T16:28:35.506Z,1751992115.506 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-07-08T16:28:35.606Z,1751992115.606 [DAT] Loaded 2025-07-08T16:28:35.606Z,1751992115.606 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-07-08T16:28:35.607Z,1751992115.607 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-07-08T16:28:35.607Z,1751992115.607 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921 2025-07-08T16:28:35.747Z,1751992115.747 [DataOverHttps] Loaded 2025-07-08T16:28:35.747Z,1751992115.747 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-07-08T16:28:35.748Z,1751992115.748 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-07-08T16:28:35.748Z,1751992115.748 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2025-07-08T16:28:35.772Z,1751992115.772 [Depth_Keller] Loaded 2025-07-08T16:28:35.773Z,1751992115.773 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-07-08T16:28:35.778Z,1751992115.778 [DropWeight] Loaded 2025-07-08T16:28:35.778Z,1751992115.778 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-07-08T16:28:35.859Z,1751992115.859 [NAL9602] Loaded 2025-07-08T16:28:35.859Z,1751992115.859 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-07-08T16:28:35.890Z,1751992115.890 [Onboard] Loaded 2025-07-08T16:28:35.890Z,1751992115.890 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-07-08T16:28:35.891Z,1751992115.891 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-07-08T16:28:35.891Z,1751992115.891 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2025-07-08T16:28:35.906Z,1751992115.906 [Power24vConverter] Loaded 2025-07-08T16:28:35.906Z,1751992115.906 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-07-08T16:28:35.923Z,1751992115.923 [Radio_Surface] Loaded 2025-07-08T16:28:35.923Z,1751992115.923 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-07-08T16:28:35.924Z,1751992115.924 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0 2025-07-08T16:28:35.924Z,1751992115.924 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2025-07-08T16:28:35.940Z,1751992115.940 [Sonardyne_Nano] Loaded 2025-07-08T16:28:35.940Z,1751992115.940 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-07-08T16:28:35.941Z,1751992115.941 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-07-08T16:28:35.942Z,1751992115.942 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-07-08T16:28:35.962Z,1751992115.962 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-07-08T16:28:35.963Z,1751992115.963 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-07-08T16:28:36.048Z,1751992116.048 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-07-08T16:28:36.049Z,1751992116.049 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-07-08T16:28:36.251Z,1751992116.251 [SBIT](DEBUG): Construct Startup Built In Test. 2025-07-08T16:28:36.261Z,1751992116.261 [SBIT] Loaded 2025-07-08T16:28:36.261Z,1751992116.261 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-07-08T16:28:36.264Z,1751992116.264 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-07-08T16:28:36.277Z,1751992116.277 [IBIT] Loaded 2025-07-08T16:28:36.278Z,1751992116.278 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-07-08T16:28:36.283Z,1751992116.283 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-07-08T16:28:36.367Z,1751992116.367 [CBIT] Loaded 2025-07-08T16:28:36.367Z,1751992116.367 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-07-08T16:28:36.402Z,1751992116.402 [GFScanner] Loaded 2025-07-08T16:28:36.402Z,1751992116.402 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-07-08T16:28:36.403Z,1751992116.403 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-07-08T16:28:36.403Z,1751992116.403 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-07-08T16:28:36.828Z,1751992116.828 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-07-08T16:28:36.828Z,1751992116.828 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-07-08T16:28:36.900Z,1751992116.900 [DepthRateCalculator] Loaded 2025-07-08T16:28:36.900Z,1751992116.900 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-07-08T16:28:36.906Z,1751992116.906 [PitchRateCalculator] Loaded 2025-07-08T16:28:36.906Z,1751992116.906 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-07-08T16:28:36.916Z,1751992116.916 [SpeedCalculator] Loaded 2025-07-08T16:28:36.917Z,1751992116.917 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-07-08T16:28:36.921Z,1751992116.921 [YawRateCalculator] Loaded 2025-07-08T16:28:36.922Z,1751992116.922 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-07-08T16:28:36.941Z,1751992116.941 [ElevatorOffsetCalculator] Loaded 2025-07-08T16:28:36.942Z,1751992116.942 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-07-08T16:28:36.942Z,1751992116.942 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-07-08T16:28:36.943Z,1751992116.943 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-07-08T16:28:37.211Z,1751992117.211 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-07-08T16:28:37.211Z,1751992117.211 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-07-08T16:28:37.353Z,1751992117.353 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-07-08T16:28:37.354Z,1751992117.354 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-07-08T16:28:37.368Z,1751992117.368 [NavChart] Loaded 2025-07-08T16:28:37.369Z,1751992117.369 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-07-08T16:28:37.375Z,1751992117.375 [UniversalFixResidualReporter] Loaded 2025-07-08T16:28:37.375Z,1751992117.375 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-07-08T16:28:37.375Z,1751992117.375 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-07-08T16:28:37.376Z,1751992117.376 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-07-08T16:28:37.548Z,1751992117.548 [BuoyancyServo] Loaded 2025-07-08T16:28:37.549Z,1751992117.549 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-07-08T16:28:37.573Z,1751992117.573 [ElevatorServo] Loaded 2025-07-08T16:28:37.573Z,1751992117.573 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-07-08T16:28:37.596Z,1751992117.596 [MassServo] Loaded 2025-07-08T16:28:37.596Z,1751992117.596 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-07-08T16:28:37.617Z,1751992117.617 [RudderServo] Loaded 2025-07-08T16:28:37.618Z,1751992117.618 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-07-08T16:28:37.635Z,1751992117.635 [ThrusterHE] Loaded 2025-07-08T16:28:37.635Z,1751992117.635 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-07-08T16:28:37.636Z,1751992117.636 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-07-08T16:28:37.636Z,1751992117.636 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-07-08T16:28:37.867Z,1751992117.867 [CTD_Seabird] Loaded 2025-07-08T16:28:37.867Z,1751992117.867 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-07-08T16:28:37.868Z,1751992117.868 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C094E0 2025-07-08T16:28:37.868Z,1751992117.868 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926 2025-07-08T16:28:37.906Z,1751992117.906 [ESPComponent] Loaded 2025-07-08T16:28:37.907Z,1751992117.907 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-07-08T16:28:37.930Z,1751992117.930 [PAR_Licor] Loaded 2025-07-08T16:28:37.930Z,1751992117.930 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-07-08T16:28:37.977Z,1751992117.977 [WetLabsBB2FL] Loaded 2025-07-08T16:28:37.977Z,1751992117.977 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-07-08T16:28:37.978Z,1751992117.978 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0 2025-07-08T16:28:37.978Z,1751992117.978 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927 2025-07-08T16:28:37.979Z,1751992117.979 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-07-08T16:28:37.986Z,1751992117.986 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-07-08T16:28:37.989Z,1751992117.989 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-07-08T16:28:38.000Z,1751992118.000 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-07-08T16:28:38.001Z,1751992118.001 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0 2025-07-08T16:28:38.002Z,1751992118.002 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2025-07-08T16:28:38.006Z,1751992118.006 [Supervisor](INFO): Main Thread ID is 828 2025-07-08T16:28:38.006Z,1751992118.006 [Supervisor](DEBUG): Running supervisor. 2025-07-08T16:28:38.007Z,1751992118.007 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929 2025-07-08T16:28:38.007Z,1751992118.007 [CommandExec](INFO): Initializing the command executive. 2025-07-08T16:28:38.009Z,1751992118.009 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930 2025-07-08T16:28:38.011Z,1751992118.011 [controlThread ThreadHandler](INFO): Handler Thread ID is 931 2025-07-08T16:28:38.011Z,1751992118.011 [controlThread](DEBUG): Initializing ControlThread 2025-07-08T16:28:38.012Z,1751992118.012 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-07-08T16:28:38.014Z,1751992118.014 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-07-08T16:28:38.015Z,1751992118.015 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-07-08T16:28:38.015Z,1751992118.015 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-07-08T16:28:38.018Z,1751992118.018 [SBIT](INFO): Initialize SBIT Component. 2025-07-08T16:28:38.018Z,1751992118.018 [SBIT](IMPORTANT): git: 2025-06-02-12-gf31d2d2cf 2025-07-08T16:28:38.019Z,1751992118.019 [SBIT](INFO): git hash: f31d2d2cff7b1a18a5fb2309356ea947ca1213be 2025-07-08T16:28:38.019Z,1751992118.019 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-07-08T16:28:38.020Z,1751992118.020 [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-07-08T16:28:38.021Z,1751992118.021 [SBIT](INFO): Beginning SBIT in 120.000000 seconds. 2025-07-08T16:28:38.022Z,1751992118.022 [IBIT](INFO): Initialize IBIT Component. 2025-07-08T16:28:38.022Z,1751992118.022 [CBIT](DEBUG): Initialize CBIT Component. 2025-07-08T16:28:38.023Z,1751992118.023 [logger ThreadHandler](INFO): Handler Thread ID is 932 2025-07-08T16:28:38.037Z,1751992118.037 [CBIT](DEBUG): Initialized mux pins. 2025-07-08T16:28:38.037Z,1751992118.037 [CBIT](DEBUG): Initializing the watchdog timer. 2025-07-08T16:28:38.049Z,1751992118.049 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933 2025-07-08T16:28:38.061Z,1751992118.061 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-07-08T16:28:38.061Z,1751992118.061 [CBIT](DEBUG): Initializing heartbeat. 2025-07-08T16:28:38.062Z,1751992118.062 [DAT ThreadHandler](INFO): Handler Thread ID is 934 2025-07-08T16:28:38.063Z,1751992118.063 [DAT](INFO): Powering up 2025-07-08T16:28:38.063Z,1751992118.063 [DAT](DEBUG): Initializing DAT. 2025-07-08T16:28:38.066Z,1751992118.066 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2025-07-08T16:28:38.068Z,1751992118.068 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-07-08T16:28:38.089Z,1751992118.089 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2025-07-08T16:28:38.107Z,1751992118.107 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2025-07-08T16:28:38.126Z,1751992118.126 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2025-07-08T16:28:38.127Z,1751992118.127 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-07-08T16:28:38.130Z,1751992118.130 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2025-07-08T16:28:38.133Z,1751992118.133 [CBIT](DEBUG): Deactivating emergency mode. 2025-07-08T16:28:38.134Z,1751992118.134 [WetLabsBB2FL](INFO): Powering up 2025-07-08T16:28:38.135Z,1751992118.135 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2025-07-08T16:28:38.141Z,1751992118.141 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-07-08T16:28:38.142Z,1751992118.142 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-07-08T16:28:38.142Z,1751992118.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-07-08T16:28:38.142Z,1751992118.142 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-07-08T16:28:38.142Z,1751992118.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-07-08T16:28:38.142Z,1751992118.142 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-07-08T16:28:38.142Z,1751992118.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-07-08T16:28:38.143Z,1751992118.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-07-08T16:28:38.173Z,1751992118.173 [CBIT](DEBUG): Backplane powered. 2025-07-08T16:28:38.173Z,1751992118.173 [GFScanner](DEBUG): Initializing GFScanner 2025-07-08T16:28:38.173Z,1751992118.173 [GFScanner](DEBUG): Deactivating GF circuits. 2025-07-08T16:28:38.178Z,1751992118.178 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-07-08T16:28:38.178Z,1751992118.178 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-07-08T16:28:38.178Z,1751992118.178 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-07-08T16:28:38.179Z,1751992118.179 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-07-08T16:28:38.179Z,1751992118.179 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-07-08T16:28:38.180Z,1751992118.180 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-07-08T16:28:38.181Z,1751992118.181 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-07-08T16:28:38.194Z,1751992118.194 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-07-08T16:28:38.194Z,1751992118.194 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-07-08T16:28:38.195Z,1751992118.195 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-07-08T16:28:38.199Z,1751992118.199 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-07-08T16:28:38.283Z,1751992118.283 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-07-08T16:28:38.305Z,1751992118.305 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-08T16:28:38.373Z,1751992118.373 [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-07-08T16:28:38.374Z,1751992118.374 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-07-08T16:28:38.374Z,1751992118.374 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-07-08T16:28:38.378Z,1751992118.378 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-07-08T16:28:38.517Z,1751992118.517 [Radio_Surface](INFO): Powering up 2025-07-08T16:28:38.669Z,1751992118.669 [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-07-08T16:28:38.675Z,1751992118.675 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-07-08T16:28:38.676Z,1751992118.676 [Default:A.Wait](DEBUG): Construct Wait. 2025-07-08T16:28:38.695Z,1751992118.695 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-08T16:28:38.723Z,1751992118.723 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-07-08T16:28:38.750Z,1751992118.750 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-07-08T16:28:38.756Z,1751992118.756 [Default:E.Execute](DEBUG): Construct Execute. 2025-07-08T16:28:38.774Z,1751992118.774 [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-07-08T16:28:38.785Z,1751992118.785 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-07-08T16:28:38.793Z,1751992118.793 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-07-08T16:28:38.942Z,1751992118.942 [Depth_Keller](INFO): Initializing. 2025-07-08T16:28:38.943Z,1751992118.943 [Power24vConverter](INFO): Powering up. 2025-07-08T16:28:38.944Z,1751992118.944 [Sonardyne_Nano](INFO): Initializing. 2025-07-08T16:28:38.970Z,1751992118.970 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-08T16:28:38.996Z,1751992118.996 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-07-08T16:28:39.001Z,1751992119.001 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-07-08T16:28:39.002Z,1751992119.002 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-08T16:28:39.009Z,1751992119.009 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-08T16:28:39.010Z,1751992119.010 [MassServo](DEBUG): Initializing EZServoServo. 2025-07-08T16:28:39.017Z,1751992119.017 [MassServo](DEBUG): Initializing MassServo. 2025-07-08T16:28:39.018Z,1751992119.018 [RudderServo](DEBUG): Initializing EZServoServo. 2025-07-08T16:28:39.025Z,1751992119.025 [RudderServo](DEBUG): Initializing RudderServo. 2025-07-08T16:28:39.026Z,1751992119.026 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-07-08T16:28:39.033Z,1751992119.033 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-07-08T16:28:39.231Z,1751992119.231 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-07-08T16:28:39.231Z,1751992119.231 [DropWeight] Hardware Fault, FailCount= 1 2025-07-08T16:28:39.231Z,1751992119.231 [DropWeight](ERROR): Hardware Fault 2025-07-08T16:28:39.267Z,1751992119.267 [CommandExec](FAULT): Scheduling is paused 2025-07-08T16:28:39.268Z,1751992119.268 [CBIT](INFO): Critical error at 20250708T162839 2025-07-08T16:28:39.268Z,1751992119.268 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-07-08T16:28:39.278Z,1751992119.278 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-07-08T16:28:39.279Z,1751992119.279 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-07-08T16:28:39.630Z,1751992119.630 [CBIT](INFO): Critical error at 20250708T162839 2025-07-08T16:28:40.241Z,1751992120.241 [WetLabsBB2FL](INFO): Powering down 2025-07-08T16:28:41.219Z,1751992121.219 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-07-08T16:28:42.461Z,1751992122.461 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-07-08T16:28:51.170Z,1751992131.170 [DAT](INFO): DAT read: 2025-07-08T16:28:51.171Z,1751992131.171 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-07-08T16:28:52.934Z,1751992132.934 [DAT](INFO): DAT read: MF Frequency Band 2025-07-08T16:28:52.935Z,1751992132.935 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-07-08T16:28:52.936Z,1751992132.936 [DAT](INFO): DAT read: Jun 11 2025 20:57:04 2025-07-08T16:28:54.194Z,1751992134.194 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-07-08T16:28:54.195Z,1751992134.195 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-07-08T16:28:54.196Z,1751992134.196 [DAT](INFO): commRate: 800 2025-07-08T16:28:56.261Z,1751992136.261 [DAT](INFO): entering command mode 2025-07-08T16:28:56.462Z,1751992136.462 [DAT](INFO): DAT read: 2025-07-08T16:28:56.462Z,1751992136.462 [DAT](INFO): DAT read: user:1> 2025-07-08T16:28:56.463Z,1751992136.463 [DAT](INFO): setting verbose to 3 2025-07-08T16:28:56.714Z,1751992136.714 [DAT](INFO): DAT read: user:1> 2025-07-08T16:28:56.715Z,1751992136.715 [DAT](INFO): DAT read: Verbose | 3 2025-07-08T16:28:56.715Z,1751992136.715 [DAT](INFO): set verbose to 3 2025-07-08T16:28:56.715Z,1751992136.715 [DAT](INFO): setting DatVerbose to 27440 2025-07-08T16:28:56.966Z,1751992136.966 [DAT](INFO): DAT read: user:2> 2025-07-08T16:28:56.966Z,1751992136.966 [DAT](INFO): DAT read: DatVerbose | 27440 2025-07-08T16:28:56.967Z,1751992136.967 [DAT](INFO): set DatVerbose to 27440 2025-07-08T16:28:56.967Z,1751992136.967 [DAT](INFO): setting transmit power to 8 2025-07-08T16:28:57.218Z,1751992137.218 [DAT](INFO): DAT read: user:3> 2025-07-08T16:28:57.219Z,1751992137.219 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-07-08T16:28:57.219Z,1751992137.219 [DAT](INFO): set transmit power to 8 2025-07-08T16:28:57.219Z,1751992137.219 [DAT](INFO): setting local address to 1 2025-07-08T16:28:57.470Z,1751992137.470 [DAT](INFO): DAT read: user:4> 2025-07-08T16:28:57.471Z,1751992137.471 [DAT](INFO): DAT read: LocalAddr | 1 2025-07-08T16:28:57.471Z,1751992137.471 [DAT](INFO): set local address to 1 2025-07-08T16:28:57.472Z,1751992137.472 [DAT](INFO): Setting time to: 16:28:57 And date to:7/8/2025 2025-07-08T16:28:57.722Z,1751992137.722 [DAT](INFO): DAT read: user:5> 2025-07-08T16:28:57.723Z,1751992137.723 [DAT](INFO): DAT read: Tue Jul 8, 2025 16:28:57 2025-07-08T16:28:57.723Z,1751992137.723 [DAT](INFO): Local DAT time set to Tue Jul 8, 2025 16:28:57 2025-07-08T16:29:05.918Z,1751992145.918 [NAL9602](INFO): Powering up NAL9602 2025-07-08T16:29:16.832Z,1751992156.832 [NAL9602](INFO): NAL9602 initialized 2025-07-08T16:30:19.921Z,1751992219.921 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:30:32.830Z,1751992232.830 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:30:38.479Z,1751992238.479 [SBIT](IMPORTANT): Beginning Startup BIT 2025-07-08T16:30:38.484Z,1751992238.484 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-08T16:30:42.966Z,1751992242.966 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.005769 CHAN A1 (24V): 0.000279 CHAN A2 (12V): 0.000839 CHAN A3 (5V): -0.000974 CHAN B0 (3.3V): 0.000405 CHAN B1 (3.15aV): 0.000529 CHAN B2 (3.15bV): -0.000269 CHAN B3 (GND): -0.000101 OPEN: 0.006150 Full Scale: +/- 1 mA 2025-07-08T16:30:45.750Z,1751992245.750 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:30:57.877Z,1751992257.877 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:31:10.398Z,1751992270.398 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:31:22.984Z,1751992282.984 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:31:31.184Z,1751992291.184 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005337 2025-07-08T16:31:31.958Z,1751992291.958 [SBIT](IMPORTANT): SBIT PASSED 2025-07-08T16:31:31.958Z,1751992291.958 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-07-08T16:31:31.959Z,1751992291.959 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-07-08T16:31:31.959Z,1751992291.959 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter; 2025-07-08T16:31:31.959Z,1751992291.959 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second; 2025-07-08T16:31:31.959Z,1751992291.959 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second; 2025-07-08T16:31:31.960Z,1751992291.960 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=208.461783 cubic_centimeter; 2025-07-08T16:31:31.960Z,1751992291.960 [SBIT](IMPORTANT): VerticalControl.massDefault=25.111432 millimeter; 2025-07-08T16:31:31.960Z,1751992291.960 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2025-07-08T16:31:31.960Z,1751992291.960 [SBIT](IMPORTANT): Waypoint.wptRecalcBearing=1 bool; 2025-07-08T16:31:32.331Z,1751992292.331 [MissionManager](IMPORTANT): Started mission Startup 2025-07-08T16:31:32.331Z,1751992292.331 [Startup] Running Loop=1 2025-07-08T16:31:32.331Z,1751992292.331 [Startup](DEBUG): Aggregate::initialize Startup 2025-07-08T16:31:32.331Z,1751992292.331 [Startup:A.GoToSurface] Running Loop=1 2025-07-08T16:31:32.332Z,1751992292.332 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-08T16:31:32.332Z,1751992292.332 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-08T16:31:32.333Z,1751992292.333 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-08T16:31:32.333Z,1751992292.333 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-08T16:31:32.334Z,1751992292.334 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-08T16:31:32.334Z,1751992292.334 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-08T16:31:32.334Z,1751992292.334 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-08T16:31:32.336Z,1751992292.336 [Startup:StartupSatComms] Running Loop=1 2025-07-08T16:31:32.336Z,1751992292.336 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-07-08T16:31:32.336Z,1751992292.336 [Startup:StartupSatComms:A] Running Loop=1 2025-07-08T16:31:32.739Z,1751992292.739 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-07-08T16:31:35.184Z,1751992295.184 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:31:38.382Z,1751992298.382 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-07-08T16:31:38.382Z,1751992298.382 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:31:38.393Z,1751992298.393 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:31:38.796Z,1751992298.796 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:31:38.796Z,1751992298.796 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-07-08T16:31:48.516Z,1751992308.516 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:32:01.447Z,1751992321.447 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:32:14.372Z,1751992334.372 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:32:21.882Z,1751992341.882 [CommandExec](IMPORTANT): got command failComponent 2025-07-08T16:32:21.882Z,1751992341.882 [CommandExec](IMPORTANT): Failed components: 2025-07-08T16:32:21.882Z,1751992341.882 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2025-07-08T16:32:27.334Z,1751992347.334 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:32:32.527Z,1751992352.527 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-08T16:31:32.3Z 2025-07-08T16:32:32.527Z,1751992352.527 [Startup:StartupSatComms:A] Stopped 2025-07-08T16:32:32.527Z,1751992352.527 [Startup:StartupSatComms:B] Running Loop=1 2025-07-08T16:32:32.923Z,1751992352.923 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-08T16:32:39.361Z,1751992359.361 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20250708T162826/Courier0000.lzma 2025-07-08T16:32:39.444Z,1751992359.444 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:32:40.363Z,1751992360.363 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Courier0000.lzma.bak 2025-07-08T16:32:40.363Z,1751992360.363 [DataOverHttps](INFO): SBD MOMSN=25430025 2025-07-08T16:32:56.027Z,1751992376.027 [DataOverHttps](INFO): Sending 1025 bytes from file Logs/20250708T162826/Express0001.lzma 2025-07-08T16:32:57.028Z,1751992377.028 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Express0001.lzma.bak 2025-07-08T16:32:57.028Z,1751992377.028 [DataOverHttps](INFO): SBD MOMSN=25430030 2025-07-08T16:32:58.428Z,1751992378.428 [Startup:StartupSatComms:B] Stopped 2025-07-08T16:32:58.428Z,1751992378.428 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-07-08T16:32:58.428Z,1751992378.428 [Startup:StartupSatComms] Stopped 2025-07-08T16:32:58.428Z,1751992378.428 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-07-08T16:32:58.451Z,1751992378.451 [Startup](INFO): Completed Startup 2025-07-08T16:32:58.452Z,1751992378.452 [MissionManager](INFO): Startup is completed. 2025-07-08T16:32:58.452Z,1751992378.452 [MissionManager](INFO): Uninitializing Mission Startup 2025-07-08T16:32:58.452Z,1751992378.452 [Startup] Stopped 2025-07-08T16:32:58.452Z,1751992378.452 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-07-08T16:32:58.452Z,1751992378.452 [Startup:A.GoToSurface] Stopped 2025-07-08T16:32:58.452Z,1751992378.452 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-08T16:32:58.787Z,1751992378.787 [MissionManager](IMPORTANT): Started mission Default 2025-07-08T16:32:58.787Z,1751992378.787 [Default] Running Loop=1 2025-07-08T16:32:58.787Z,1751992378.787 [Default](DEBUG): Aggregate::initialize Default 2025-07-08T16:32:58.787Z,1751992378.787 [Default:B.GoToSurface] Running Loop=1 2025-07-08T16:32:58.787Z,1751992378.787 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-08T16:32:58.788Z,1751992378.788 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-08T16:32:58.788Z,1751992378.788 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-08T16:32:58.788Z,1751992378.788 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-08T16:32:58.788Z,1751992378.788 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-08T16:32:58.789Z,1751992378.789 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-08T16:32:58.789Z,1751992378.789 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-08T16:32:58.790Z,1751992378.790 [Default:A.Wait] Running Loop=1 2025-07-08T16:32:58.790Z,1751992378.790 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-07-08T16:33:10.127Z,1751992390.127 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:33:12.111Z,1751992392.111 [Default:A.Wait](INFO): Done Waiting. 2025-07-08T16:33:12.111Z,1751992392.111 [Default:A.Wait] Stopped 2025-07-08T16:33:12.111Z,1751992392.111 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T16:33:12.517Z,1751992392.517 [Default:CheckIn] Running Loop=1 2025-07-08T16:33:12.517Z,1751992392.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T16:33:12.517Z,1751992392.517 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T16:33:12.931Z,1751992392.931 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-07-08T16:33:21.441Z,1751992401.441 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:34:19.223Z,1751992459.223 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-08T16:34:19.983Z,1751992459.983 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-08T16:34:32.197Z,1751992472.197 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-08T16:34:39.381Z,1751992479.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-07-08T16:34:39.381Z,1751992479.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:34:39.392Z,1751992479.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:34:39.799Z,1751992479.799 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:34:39.799Z,1751992479.799 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-07-08T16:34:58.812Z,1751992498.812 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-08T16:35:03.599Z,1751992503.599 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-07-08T16:35:10.467Z,1751992510.467 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T16:35:25.494Z,1751992525.494 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T16:35:40.767Z,1751992540.767 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T16:35:55.715Z,1751992555.715 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T16:36:11.474Z,1751992571.474 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T16:36:15.195Z,1751992575.195 [CommandExec](IMPORTANT): got command failComponent none DropWeight 2025-07-08T16:36:15.195Z,1751992575.195 [DropWeight] No Fault, FailCount= 1 2025-07-08T16:36:15.195Z,1751992575.195 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault 2025-07-08T16:36:17.886Z,1751992577.886 [CommandExec](IMPORTANT): got command failComponent 2025-07-08T16:36:17.886Z,1751992577.886 [CommandExec](IMPORTANT): Failed components: 2025-07-08T16:36:17.886Z,1751992577.886 [CommandExec](IMPORTANT): No failed Components. 2025-07-08T16:36:18.807Z,1751992578.807 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-08T16:36:19.446Z,1751992579.446 [CommandExec](IMPORTANT): got command failComponent 2025-07-08T16:36:19.446Z,1751992579.446 [CommandExec](IMPORTANT): Failed components: 2025-07-08T16:36:19.446Z,1751992579.446 [CommandExec](IMPORTANT): No failed Components. 2025-07-08T16:36:20.594Z,1751992580.594 [CommandExec](IMPORTANT): got command failComponent 2025-07-08T16:36:20.594Z,1751992580.594 [CommandExec](IMPORTANT): Failed components: 2025-07-08T16:36:20.594Z,1751992580.594 [CommandExec](IMPORTANT): No failed Components. 2025-07-08T16:36:21.838Z,1751992581.838 [CommandExec](IMPORTANT): got command failComponent 2025-07-08T16:36:21.838Z,1751992581.838 [CommandExec](IMPORTANT): Failed components: 2025-07-08T16:36:21.838Z,1751992581.838 [CommandExec](IMPORTANT): No failed Components. 2025-07-08T16:36:24.851Z,1751992584.851 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:36:40.559Z,1751992600.559 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T16:36:55.154Z,1751992615.154 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-08T16:37:04.155Z,1751992624.155 [CommandExec](IMPORTANT): got command configSet list 2025-07-08T16:37:04.155Z,1751992624.155 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-07-08T16:37:04.156Z,1751992624.156 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-07-08T16:37:04.157Z,1751992624.157 [CommandExec](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter; 2025-07-08T16:37:04.160Z,1751992624.160 [CommandExec](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second; 2025-07-08T16:37:04.160Z,1751992624.160 [CommandExec](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second; 2025-07-08T16:37:04.161Z,1751992624.161 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=208.461783 cubic_centimeter; 2025-07-08T16:37:04.210Z,1751992624.210 [CommandExec](IMPORTANT): VerticalControl.massDefault=25.111432 millimeter; 2025-07-08T16:37:04.210Z,1751992624.210 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2025-07-08T16:37:04.211Z,1751992624.211 [CommandExec](IMPORTANT): Waypoint.wptRecalcBearing=1 bool; 2025-07-08T16:37:09.249Z,1751992629.249 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T16:37:39.715Z,1751992659.715 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2025-07-08T16:37:39.715Z,1751992659.715 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2025-07-08T16:37:40.389Z,1751992660.389 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-07-08T16:37:40.389Z,1751992660.389 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:37:40.400Z,1751992660.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:37:40.811Z,1751992660.811 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:37:40.811Z,1751992660.811 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-07-08T16:37:43.042Z,1751992663.042 [CommandExec](IMPORTANT): got command strobe off 2025-07-08T16:37:43.043Z,1751992663.043 [CommandExec](IMPORTANT): Deactivating strobe 2025-07-08T16:38:12.687Z,1751992692.687 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-08T16:33:12.5Z 2025-07-08T16:38:12.687Z,1751992692.687 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T16:38:12.687Z,1751992692.687 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T16:38:13.092Z,1751992693.092 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-08T16:38:20.526Z,1751992700.526 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250708T162826/Courier0004.lzma 2025-07-08T16:38:21.527Z,1751992701.527 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Courier0004.lzma.bak 2025-07-08T16:38:21.527Z,1751992701.527 [DataOverHttps](INFO): SBD MOMSN=25430196 2025-07-08T16:38:37.310Z,1751992717.310 [DataOverHttps](INFO): Sending 713 bytes from file Logs/20250708T162826/Express0005.lzma 2025-07-08T16:38:38.311Z,1751992718.311 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Express0005.lzma.bak 2025-07-08T16:38:38.311Z,1751992718.311 [DataOverHttps](INFO): SBD MOMSN=25430198 2025-07-08T16:38:39.392Z,1751992719.392 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T16:38:39.392Z,1751992719.392 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T16:38:39.392Z,1751992719.392 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T16:39:18.951Z,1751992758.951 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-08T16:39:18.951Z,1751992758.951 [NAL9602] Data Fault, FailCount= 1 2025-07-08T16:39:18.951Z,1751992758.951 [NAL9602](ERROR): Data Fault 2025-07-08T16:39:18.984Z,1751992758.984 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-08T16:39:19.350Z,1751992759.350 [NAL9602](INFO): Powering down 2025-07-08T16:39:20.197Z,1751992760.197 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-08T16:39:20.197Z,1751992760.197 [NAL9602] No Fault, FailCount= 1 2025-07-08T16:39:43.190Z,1751992783.190 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-08T16:39:43.191Z,1751992783.191 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-08T16:39:43.192Z,1751992783.192 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-08T16:39:43.192Z,1751992783.192 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-08T16:39:43.197Z,1751992783.197 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0. 2025-07-08T16:39:43.198Z,1751992783.198 [BPC1](INFO): Calculating totals. Valid battery stick count: 38. Valid reserve battery stick count: 4. 2025-07-08T16:39:43.225Z,1751992783.225 [BPC1](INFO): Received data from all battery sticks. 2025-07-08T16:39:49.652Z,1751992789.652 [NAL9602](INFO): Powering up NAL9602 2025-07-08T16:40:00.563Z,1751992800.563 [NAL9602](INFO): NAL9602 initialized 2025-07-08T16:40:41.374Z,1751992841.374 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-07-08T16:40:41.374Z,1751992841.374 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:40:41.411Z,1751992841.411 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:40:41.788Z,1751992841.788 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:40:41.788Z,1751992841.788 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-07-08T16:41:48.734Z,1751992908.734 [CommandExec](IMPORTANT): got command burn on 2025-07-08T16:41:48.734Z,1751992908.734 [CommandExec](IMPORTANT): Activating dropweight wire 2025-07-08T16:43:39.950Z,1751993019.950 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-08T16:43:39.950Z,1751993019.950 [Default:CheckIn:C.Wait] Stopped 2025-07-08T16:43:39.950Z,1751993019.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T16:43:39.951Z,1751993019.951 [Default:CheckIn:D] Running Loop=1 2025-07-08T16:43:40.351Z,1751993020.351 [Default:CheckIn:D] Stopped 2025-07-08T16:43:40.351Z,1751993020.351 [Default:CheckIn:E] Running Loop=1 2025-07-08T16:43:40.763Z,1751993020.763 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.692735 min 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn:E] Stopped 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn] Stopped 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn](INFO): Running loop #2 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn] Running Loop=2 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T16:43:40.764Z,1751993020.764 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T16:43:42.366Z,1751993022.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-07-08T16:43:42.366Z,1751993022.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:43:42.376Z,1751993022.376 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:43:42.782Z,1751993022.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:43:42.782Z,1751993022.782 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-07-08T16:44:26.258Z,1751993066.258 [CommandExec](IMPORTANT): got command burn off 2025-07-08T16:44:26.258Z,1751993066.258 [CommandExec](IMPORTANT): Deactivating dropweight wire 2025-07-08T16:44:37.399Z,1751993077.399 [CommandExec](IMPORTANT): got command burn off 2025-07-08T16:44:37.399Z,1751993077.399 [CommandExec](IMPORTANT): Deactivating dropweight wire 2025-07-08T16:46:43.364Z,1751993203.364 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-07-08T16:46:43.364Z,1751993203.364 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:46:43.375Z,1751993203.375 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:46:43.788Z,1751993203.788 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:46:43.788Z,1751993203.788 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-07-08T16:48:40.943Z,1751993320.943 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-08T16:43:40.8Z 2025-07-08T16:48:40.943Z,1751993320.943 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T16:48:40.944Z,1751993320.944 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T16:48:47.845Z,1751993327.845 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250708T162826/Courier0007.lzma 2025-07-08T16:48:48.847Z,1751993328.847 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Courier0007.lzma.bak 2025-07-08T16:48:48.847Z,1751993328.847 [DataOverHttps](INFO): SBD MOMSN=25430385 2025-07-08T16:49:04.425Z,1751993344.425 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20250708T162826/Express0008.lzma 2025-07-08T16:49:05.427Z,1751993345.427 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Express0008.lzma.bak 2025-07-08T16:49:05.427Z,1751993345.427 [DataOverHttps](INFO): SBD MOMSN=25430387 2025-07-08T16:49:06.783Z,1751993346.783 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T16:49:06.783Z,1751993346.783 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T16:49:06.783Z,1751993346.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T16:49:44.351Z,1751993384.351 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-07-08T16:49:44.351Z,1751993384.351 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:49:44.362Z,1751993384.362 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:49:44.776Z,1751993384.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:49:44.776Z,1751993384.776 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-07-08T16:50:03.732Z,1751993403.732 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-08T16:50:03.732Z,1751993403.732 [NAL9602] Data Fault, FailCount= 2 2025-07-08T16:50:03.732Z,1751993403.732 [NAL9602](ERROR): Data Fault 2025-07-08T16:50:03.750Z,1751993403.750 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-08T16:50:04.134Z,1751993404.134 [NAL9602](INFO): Powering down 2025-07-08T16:50:04.963Z,1751993404.963 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-08T16:50:04.963Z,1751993404.963 [NAL9602] No Fault, FailCount= 2 2025-07-08T16:50:34.438Z,1751993434.438 [NAL9602](INFO): Powering up NAL9602 2025-07-08T16:50:45.343Z,1751993445.343 [NAL9602](INFO): NAL9602 initialized 2025-07-08T16:52:35.397Z,1751993555.397 [CommandExec](IMPORTANT): got command configSet list 2025-07-08T16:52:35.397Z,1751993555.397 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-07-08T16:52:35.398Z,1751993555.398 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-07-08T16:52:35.399Z,1751993555.399 [CommandExec](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter; 2025-07-08T16:52:35.400Z,1751993555.400 [CommandExec](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second; 2025-07-08T16:52:35.400Z,1751993555.400 [CommandExec](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second; 2025-07-08T16:52:35.401Z,1751993555.401 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=208.461783 cubic_centimeter; 2025-07-08T16:52:35.402Z,1751993555.402 [CommandExec](IMPORTANT): VerticalControl.massDefault=25.111432 millimeter; 2025-07-08T16:52:35.403Z,1751993555.403 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2025-07-08T16:52:35.403Z,1751993555.403 [CommandExec](IMPORTANT): Waypoint.wptRecalcBearing=1 bool; 2025-07-08T16:52:45.362Z,1751993565.362 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-07-08T16:52:45.362Z,1751993565.362 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:52:45.373Z,1751993565.373 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:52:45.763Z,1751993565.763 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:52:45.763Z,1751993565.763 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2025-07-08T16:54:07.380Z,1751993647.380 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-08T16:54:07.380Z,1751993647.380 [Default:CheckIn:C.Wait] Stopped 2025-07-08T16:54:07.380Z,1751993647.380 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T16:54:07.380Z,1751993647.380 [Default:CheckIn:D] Running Loop=1 2025-07-08T16:54:07.775Z,1751993647.775 [Default:CheckIn:D] Stopped 2025-07-08T16:54:07.775Z,1751993647.775 [Default:CheckIn:E] Running Loop=1 2025-07-08T16:54:08.183Z,1751993648.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.149801 min 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn:E] Stopped 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn] Stopped 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn](INFO): Running loop #3 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn] Running Loop=3 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T16:54:08.184Z,1751993648.184 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T16:55:46.342Z,1751993746.342 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2025-07-08T16:55:46.342Z,1751993746.342 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:55:46.361Z,1751993746.361 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:55:46.768Z,1751993746.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:55:46.768Z,1751993746.768 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2025-07-08T16:56:56.118Z,1751993816.118 [CommandExec](IMPORTANT): got command failComponent 2025-07-08T16:56:56.118Z,1751993816.118 [CommandExec](IMPORTANT): Failed components: 2025-07-08T16:56:56.118Z,1751993816.118 [CommandExec](IMPORTANT): No failed Components. 2025-07-08T16:58:47.326Z,1751993927.326 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2025-07-08T16:58:47.327Z,1751993927.327 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T16:58:47.341Z,1751993927.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T16:58:47.748Z,1751993927.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T16:58:47.748Z,1751993927.748 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2025-07-08T16:59:08.359Z,1751993948.359 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-08T16:54:08.2Z 2025-07-08T16:59:08.359Z,1751993948.359 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T16:59:08.359Z,1751993948.359 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T16:59:15.500Z,1751993955.500 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250708T162826/Courier0010.lzma 2025-07-08T16:59:16.503Z,1751993956.503 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Courier0010.lzma.bak 2025-07-08T16:59:16.503Z,1751993956.503 [DataOverHttps](INFO): SBD MOMSN=25430546 2025-07-08T16:59:32.102Z,1751993972.102 [DataOverHttps](INFO): Sending 538 bytes from file Logs/20250708T162826/Express0011.lzma 2025-07-08T16:59:33.103Z,1751993973.103 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Express0011.lzma.bak 2025-07-08T16:59:33.103Z,1751993973.103 [DataOverHttps](INFO): SBD MOMSN=25430548 2025-07-08T16:59:34.190Z,1751993974.190 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T16:59:34.190Z,1751993974.190 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T16:59:34.190Z,1751993974.190 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T17:00:47.303Z,1751994047.303 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-08T17:00:47.303Z,1751994047.303 [NAL9602] Data Fault, FailCount= 3 2025-07-08T17:00:47.303Z,1751994047.303 [NAL9602](ERROR): Data Fault 2025-07-08T17:00:47.345Z,1751994047.345 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-08T17:00:47.710Z,1751994047.710 [NAL9602](INFO): Powering down 2025-07-08T17:00:48.540Z,1751994048.540 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-08T17:00:48.540Z,1751994048.540 [NAL9602] No Fault, FailCount= 3 2025-07-08T17:01:18.010Z,1751994078.010 [NAL9602](INFO): Powering up NAL9602 2025-07-08T17:01:28.919Z,1751994088.919 [NAL9602](INFO): NAL9602 initialized 2025-07-08T17:01:48.317Z,1751994108.317 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2025-07-08T17:01:48.318Z,1751994108.318 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T17:01:48.328Z,1751994108.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T17:01:48.739Z,1751994108.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T17:01:48.740Z,1751994108.740 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2025-07-08T17:04:34.763Z,1751994274.763 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-08T17:04:34.764Z,1751994274.764 [Default:CheckIn:C.Wait] Stopped 2025-07-08T17:04:34.764Z,1751994274.764 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T17:04:34.764Z,1751994274.764 [Default:CheckIn:D] Running Loop=1 2025-07-08T17:04:35.187Z,1751994275.187 [Default:CheckIn:D] Stopped 2025-07-08T17:04:35.187Z,1751994275.187 [Default:CheckIn:E] Running Loop=1 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.606669 min 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn:E] Stopped 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn] Stopped 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn](INFO): Running loop #4 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn] Running Loop=4 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T17:04:35.607Z,1751994275.607 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T17:04:49.310Z,1751994289.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2025-07-08T17:04:49.310Z,1751994289.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T17:04:49.321Z,1751994289.321 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T17:04:49.731Z,1751994289.731 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T17:04:49.731Z,1751994289.731 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2025-07-08T17:07:50.306Z,1751994470.306 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2025-07-08T17:07:50.306Z,1751994470.306 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T17:07:50.317Z,1751994470.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T17:07:50.722Z,1751994470.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T17:07:50.722Z,1751994470.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2025-07-08T17:09:35.768Z,1751994575.768 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-08T17:04:35.6Z 2025-07-08T17:09:35.768Z,1751994575.768 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T17:09:35.768Z,1751994575.768 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T17:09:43.312Z,1751994583.312 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250708T162826/Courier0013.lzma 2025-07-08T17:09:44.315Z,1751994584.315 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Courier0013.lzma.bak 2025-07-08T17:09:44.315Z,1751994584.315 [DataOverHttps](INFO): SBD MOMSN=25430719 2025-07-08T17:09:59.945Z,1751994599.945 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250708T162826/Express0014.lzma 2025-07-08T17:10:00.947Z,1751994600.947 [DataOverHttps](INFO): Moved sent file to Logs/20250708T162826/Express0014.lzma.bak 2025-07-08T17:10:00.947Z,1751994600.947 [DataOverHttps](INFO): SBD MOMSN=25430721 2025-07-08T17:10:02.027Z,1751994602.027 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T17:10:02.027Z,1751994602.027 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T17:10:02.027Z,1751994602.027 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T17:10:51.526Z,1751994651.526 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2025-07-08T17:10:51.526Z,1751994651.526 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-08T17:10:51.545Z,1751994651.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-08T17:10:51.956Z,1751994651.956 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-08T17:10:51.956Z,1751994651.956 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2025-07-08T17:11:31.919Z,1751994691.919 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-08T17:11:31.919Z,1751994691.919 [NAL9602] Data Fault, FailCount= 4 2025-07-08T17:11:31.919Z,1751994691.919 [NAL9602](ERROR): Data Fault 2025-07-08T17:11:31.956Z,1751994691.956 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-08T17:11:32.328Z,1751994692.328 [NAL9602](INFO): Powering down 2025-07-08T17:11:33.155Z,1751994693.155 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-08T17:11:33.155Z,1751994693.155 [NAL9602] No Fault, FailCount= 4 2025-07-08T17:12:02.434Z,1751994722.434 [CommandExec](IMPORTANT): got command restart system 2025-07-08T17:12:02.663Z,1751994722.663 [NAL9602](INFO): Powering up NAL9602 2025-07-08T17:12:04.561Z,1751994724.561 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-08T17:12:04.561Z,1751994724.561 [CommandExec](INFO): Uninitializing the command executive. 2025-07-08T17:12:04.562Z,1751994724.562 [CommandExec](INFO): Uninitializing the command scheduler. 2025-07-08T17:12:04.562Z,1751994724.562 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:04.633Z,1751994724.633 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-07-08T17:12:04.633Z,1751994724.633 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-07-08T17:12:04.633Z,1751994724.633 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:04.634Z,1751994724.634 [NavChartDb](INFO): Join timeout helper Thread ID is 1076 2025-07-08T17:12:04.905Z,1751994724.905 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-08T17:12:04.906Z,1751994724.906 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:04.925Z,1751994724.925 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-07-08T17:12:04.925Z,1751994724.925 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:04.926Z,1751994724.926 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1077 2025-07-08T17:12:05.145Z,1751994725.145 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-08T17:12:05.146Z,1751994725.146 [WetLabsBB2FL](INFO): Powering down 2025-07-08T17:12:05.147Z,1751994725.147 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:05.153Z,1751994725.153 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-07-08T17:12:05.153Z,1751994725.153 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:05.154Z,1751994725.154 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1078 2025-07-08T17:12:05.201Z,1751994725.201 [CTD_Seabird](INFO): Powering down 2025-07-08T17:12:05.213Z,1751994725.213 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-08T17:12:05.213Z,1751994725.213 [CTD_Seabird](INFO): Powering down 2025-07-08T17:12:05.225Z,1751994725.225 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:05.227Z,1751994725.227 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-07-08T17:12:05.227Z,1751994725.227 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-08T17:12:05.228Z,1751994725.228 [Radio_Surface](INFO): Join timeout helper Thread ID is 1079 2025-07-08T17:12:05.597Z,1751994725.597 [Radio_Surface](INFO): Powering down 2025-07-08T17:12:05.598Z,1751994725.598 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-08T17:12:05.598Z,1751994725.598 [Radio_Surface ThreadHandler](INFO): Thread ca