2025-03-18T18:30:04.774Z,1742322604.774 [Supervisor](DEBUG): Initializing supervisor. 2025-03-18T18:30:04.778Z,1742322604.778 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-18T18:30:04.779Z,1742322604.779 [SyncHandler](INFO): Protected caller Thread ID is 836 2025-03-18T18:30:04.779Z,1742322604.779 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-18T18:30:04.780Z,1742322604.780 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-18T18:30:04.780Z,1742322604.780 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837 2025-03-18T18:30:04.785Z,1742322604.785 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-18T18:30:04.803Z,1742322604.803 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-18T18:30:04.804Z,1742322604.804 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-18T18:30:04.805Z,1742322604.805 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838 2025-03-18T18:30:04.809Z,1742322604.809 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-18T18:30:04.810Z,1742322604.810 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-18T18:30:04.810Z,1742322604.810 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839 2025-03-18T18:30:04.813Z,1742322604.813 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-18T18:30:04.814Z,1742322604.814 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-18T18:30:04.814Z,1742322604.814 [logger ThreadHandler](INFO): Protected caller Thread ID is 840 2025-03-18T18:30:04.818Z,1742322604.818 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-18T18:30:04.818Z,1742322604.818 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-18T18:30:04.822Z,1742322604.822 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-18T18:30:05.170Z,1742322605.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-18T18:30:05.172Z,1742322605.172 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-18T18:30:05.263Z,1742322605.263 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-18T18:30:05.380Z,1742322605.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-18T18:30:05.381Z,1742322605.381 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-18T18:30:05.521Z,1742322605.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-18T18:30:05.523Z,1742322605.523 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-18T18:30:05.894Z,1742322605.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-18T18:30:05.895Z,1742322605.895 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-18T18:30:06.002Z,1742322606.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-18T18:30:06.003Z,1742322606.003 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-18T18:30:06.433Z,1742322606.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-18T18:30:06.434Z,1742322606.434 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-18T18:30:06.669Z,1742322606.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-18T18:30:06.670Z,1742322606.670 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-18T18:30:06.762Z,1742322606.762 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-18T18:30:07.013Z,1742322607.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-18T18:30:07.015Z,1742322607.015 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-18T18:30:07.290Z,1742322607.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-18T18:30:07.291Z,1742322607.291 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-18T18:30:07.829Z,1742322607.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-18T18:30:07.829Z,1742322607.829 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-18T18:30:08.029Z,1742322608.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-18T18:30:08.052Z,1742322608.052 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-18T18:30:08.491Z,1742322608.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-18T18:30:08.492Z,1742322608.492 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-18T18:30:09.366Z,1742322609.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-18T18:30:09.366Z,1742322609.366 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-18T18:30:09.942Z,1742322609.942 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-18T18:30:09.944Z,1742322609.944 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-03-18T18:30:09.947Z,1742322609.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-03-18T18:30:10.053Z,1742322610.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-03-18T18:30:10.194Z,1742322610.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-03-18T18:30:10.288Z,1742322610.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-03-18T18:30:10.406Z,1742322610.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-03-18T18:30:10.502Z,1742322610.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-03-18T18:30:10.772Z,1742322610.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-18T18:30:10.773Z,1742322610.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-03-18T18:30:10.893Z,1742322610.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-03-18T18:30:11.001Z,1742322611.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-03-18T18:30:11.151Z,1742322611.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-03-18T18:30:11.248Z,1742322611.248 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-03-18T18:30:11.252Z,1742322611.252 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-18T18:30:11.422Z,1742322611.422 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-18T18:30:11.422Z,1742322611.422 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-18T18:30:11.494Z,1742322611.494 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-18T18:30:11.556Z,1742322611.556 [VerticalControl] Loaded 2025-03-18T18:30:11.556Z,1742322611.556 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-18T18:30:11.559Z,1742322611.559 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-18T18:30:11.606Z,1742322611.606 [HorizontalControl] Loaded 2025-03-18T18:30:11.606Z,1742322611.606 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-18T18:30:11.609Z,1742322611.609 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-18T18:30:11.612Z,1742322611.612 [SpeedControl] Loaded 2025-03-18T18:30:11.612Z,1742322611.612 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-18T18:30:11.615Z,1742322611.615 [LoopControl](DEBUG): Construct LoopControl. 2025-03-18T18:30:11.615Z,1742322611.615 [LoopControl] Loaded 2025-03-18T18:30:11.616Z,1742322611.616 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-18T18:30:11.616Z,1742322611.616 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-18T18:30:11.617Z,1742322611.617 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-18T18:30:11.703Z,1742322611.703 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-18T18:30:11.704Z,1742322611.704 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-18T18:30:12.546Z,1742322612.546 [AHRS_M2] Loaded 2025-03-18T18:30:12.547Z,1742322612.547 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-18T18:30:12.799Z,1742322612.799 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4 2025-03-18T18:30:12.800Z,1742322612.800 [BackseatComponent] Loaded 2025-03-18T18:30:12.800Z,1742322612.800 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-03-18T18:30:12.801Z,1742322612.801 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-03-18T18:30:12.801Z,1742322612.801 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920 2025-03-18T18:30:12.804Z,1742322612.804 [LcmUniversalReporter] Loaded 2025-03-18T18:30:12.805Z,1742322612.805 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-03-18T18:30:13.601Z,1742322613.601 [BPC1] Loaded 2025-03-18T18:30:13.601Z,1742322613.601 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-18T18:30:13.706Z,1742322613.706 [DAT] Loaded 2025-03-18T18:30:13.707Z,1742322613.707 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-03-18T18:30:13.711Z,1742322613.711 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-03-18T18:30:13.711Z,1742322613.711 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921 2025-03-18T18:30:13.857Z,1742322613.857 [DataOverHttps] Loaded 2025-03-18T18:30:13.858Z,1742322613.858 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-18T18:30:13.859Z,1742322613.859 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-03-18T18:30:13.859Z,1742322613.859 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2025-03-18T18:30:13.882Z,1742322613.882 [Depth_Keller] Loaded 2025-03-18T18:30:13.882Z,1742322613.882 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-18T18:30:13.887Z,1742322613.887 [DropWeight] Loaded 2025-03-18T18:30:13.887Z,1742322613.887 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-18T18:30:13.951Z,1742322613.951 [NAL9602] Loaded 2025-03-18T18:30:13.951Z,1742322613.951 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-18T18:30:13.981Z,1742322613.981 [Onboard] Loaded 2025-03-18T18:30:13.981Z,1742322613.981 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-18T18:30:13.982Z,1742322613.982 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-03-18T18:30:13.983Z,1742322613.983 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2025-03-18T18:30:13.997Z,1742322613.997 [Power24vConverter] Loaded 2025-03-18T18:30:13.997Z,1742322613.997 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-18T18:30:14.017Z,1742322614.017 [Radio_Surface] Loaded 2025-03-18T18:30:14.017Z,1742322614.017 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-18T18:30:14.019Z,1742322614.019 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0 2025-03-18T18:30:14.020Z,1742322614.020 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2025-03-18T18:30:14.046Z,1742322614.046 [Sonardyne_Nano] Loaded 2025-03-18T18:30:14.046Z,1742322614.046 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-03-18T18:30:14.071Z,1742322614.071 [Waterlinked] Loaded 2025-03-18T18:30:14.071Z,1742322614.071 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-03-18T18:30:14.072Z,1742322614.072 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-18T18:30:14.073Z,1742322614.073 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-18T18:30:14.088Z,1742322614.088 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-18T18:30:14.089Z,1742322614.089 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-18T18:30:14.136Z,1742322614.136 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-18T18:30:14.137Z,1742322614.137 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-18T18:30:14.334Z,1742322614.334 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-18T18:30:14.343Z,1742322614.343 [SBIT] Loaded 2025-03-18T18:30:14.343Z,1742322614.343 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-18T18:30:14.346Z,1742322614.346 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-18T18:30:14.359Z,1742322614.359 [IBIT] Loaded 2025-03-18T18:30:14.359Z,1742322614.359 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-18T18:30:14.365Z,1742322614.365 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-18T18:30:14.445Z,1742322614.445 [CBIT] Loaded 2025-03-18T18:30:14.445Z,1742322614.445 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-18T18:30:14.478Z,1742322614.478 [GFScanner] Loaded 2025-03-18T18:30:14.479Z,1742322614.479 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-18T18:30:14.479Z,1742322614.479 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-18T18:30:14.480Z,1742322614.480 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-18T18:30:14.892Z,1742322614.892 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-18T18:30:14.893Z,1742322614.893 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-18T18:30:14.964Z,1742322614.964 [DepthRateCalculator] Loaded 2025-03-18T18:30:14.964Z,1742322614.964 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-18T18:30:14.969Z,1742322614.969 [PitchRateCalculator] Loaded 2025-03-18T18:30:14.970Z,1742322614.970 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-18T18:30:14.980Z,1742322614.980 [SpeedCalculator] Loaded 2025-03-18T18:30:14.980Z,1742322614.980 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-18T18:30:14.985Z,1742322614.985 [YawRateCalculator] Loaded 2025-03-18T18:30:14.985Z,1742322614.985 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-18T18:30:15.004Z,1742322615.004 [ElevatorOffsetCalculator] Loaded 2025-03-18T18:30:15.004Z,1742322615.004 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-18T18:30:15.005Z,1742322615.005 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-18T18:30:15.005Z,1742322615.005 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-18T18:30:15.221Z,1742322615.221 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-18T18:30:15.222Z,1742322615.222 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-18T18:30:15.362Z,1742322615.362 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-18T18:30:15.362Z,1742322615.362 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-18T18:30:15.376Z,1742322615.376 [NavChart] Loaded 2025-03-18T18:30:15.376Z,1742322615.376 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-18T18:30:15.382Z,1742322615.382 [UniversalFixResidualReporter] Loaded 2025-03-18T18:30:15.382Z,1742322615.382 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-18T18:30:15.383Z,1742322615.383 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-18T18:30:15.383Z,1742322615.383 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-18T18:30:15.551Z,1742322615.551 [BuoyancyServo] Loaded 2025-03-18T18:30:15.551Z,1742322615.551 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-18T18:30:15.574Z,1742322615.574 [ElevatorServo] Loaded 2025-03-18T18:30:15.574Z,1742322615.574 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-18T18:30:15.596Z,1742322615.596 [MassServo] Loaded 2025-03-18T18:30:15.596Z,1742322615.596 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-18T18:30:15.617Z,1742322615.617 [RudderServo] Loaded 2025-03-18T18:30:15.618Z,1742322615.618 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-18T18:30:15.635Z,1742322615.635 [ThrusterHE] Loaded 2025-03-18T18:30:15.635Z,1742322615.635 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-18T18:30:15.635Z,1742322615.635 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-18T18:30:15.636Z,1742322615.636 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-18T18:30:15.851Z,1742322615.851 [CTD_Seabird] Loaded 2025-03-18T18:30:15.851Z,1742322615.851 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-18T18:30:15.852Z,1742322615.852 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0 2025-03-18T18:30:15.853Z,1742322615.853 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925 2025-03-18T18:30:15.889Z,1742322615.889 [ESPComponent] Loaded 2025-03-18T18:30:15.890Z,1742322615.890 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-03-18T18:30:15.912Z,1742322615.912 [PAR_Licor] Loaded 2025-03-18T18:30:15.912Z,1742322615.912 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-18T18:30:15.958Z,1742322615.958 [WetLabsBB2FL] Loaded 2025-03-18T18:30:15.958Z,1742322615.958 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-03-18T18:30:15.959Z,1742322615.959 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-03-18T18:30:15.960Z,1742322615.960 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 926 2025-03-18T18:30:15.961Z,1742322615.961 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-18T18:30:15.967Z,1742322615.967 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-18T18:30:15.970Z,1742322615.970 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-18T18:30:15.981Z,1742322615.981 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-18T18:30:15.982Z,1742322615.982 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-03-18T18:30:15.983Z,1742322615.983 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927 2025-03-18T18:30:15.987Z,1742322615.987 [Supervisor](INFO): Main Thread ID is 827 2025-03-18T18:30:15.987Z,1742322615.987 [Supervisor](DEBUG): Running supervisor. 2025-03-18T18:30:15.988Z,1742322615.988 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928 2025-03-18T18:30:15.988Z,1742322615.988 [CommandExec](INFO): Initializing the command executive. 2025-03-18T18:30:15.990Z,1742322615.990 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929 2025-03-18T18:30:15.992Z,1742322615.992 [controlThread ThreadHandler](INFO): Handler Thread ID is 930 2025-03-18T18:30:15.992Z,1742322615.992 [controlThread](DEBUG): Initializing ControlThread 2025-03-18T18:30:15.993Z,1742322615.993 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-18T18:30:15.995Z,1742322615.995 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-18T18:30:15.996Z,1742322615.996 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-18T18:30:15.996Z,1742322615.996 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-18T18:30:16.002Z,1742322616.002 [SBIT](INFO): Initialize SBIT Component. 2025-03-18T18:30:16.002Z,1742322616.002 [SBIT](IMPORTANT): git: 2025-03-12_C 2025-03-18T18:30:16.003Z,1742322616.003 [SBIT](INFO): git hash: c930cdb2e5c1f1ff3673ee566a0d82f2333fbba8 2025-03-18T18:30:16.003Z,1742322616.003 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-18T18:30:16.004Z,1742322616.004 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2025-03-18T18:30:16.005Z,1742322616.005 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2025-03-18T18:30:16.006Z,1742322616.006 [IBIT](INFO): Initialize IBIT Component. 2025-03-18T18:30:16.006Z,1742322616.006 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-18T18:30:16.007Z,1742322616.007 [logger ThreadHandler](INFO): Handler Thread ID is 931 2025-03-18T18:30:16.020Z,1742322616.020 [CBIT](DEBUG): Initialized mux pins. 2025-03-18T18:30:16.020Z,1742322616.020 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-03-18T18:30:16.020Z,1742322616.020 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-18T18:30:16.028Z,1742322616.028 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932 2025-03-18T18:30:16.040Z,1742322616.040 [DAT ThreadHandler](INFO): Handler Thread ID is 933 2025-03-18T18:30:16.041Z,1742322616.041 [DAT](INFO): Powering up 2025-03-18T18:30:16.041Z,1742322616.041 [DAT](DEBUG): Initializing DAT. 2025-03-18T18:30:16.044Z,1742322616.044 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-18T18:30:16.044Z,1742322616.044 [CBIT](DEBUG): Initializing heartbeat. 2025-03-18T18:30:16.046Z,1742322616.046 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935 2025-03-18T18:30:16.048Z,1742322616.048 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-18T18:30:16.057Z,1742322616.057 [Onboard ThreadHandler](INFO): Handler Thread ID is 936 2025-03-18T18:30:16.074Z,1742322616.074 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937 2025-03-18T18:30:16.092Z,1742322616.092 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938 2025-03-18T18:30:16.093Z,1742322616.093 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-18T18:30:16.097Z,1742322616.097 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940 2025-03-18T18:30:16.100Z,1742322616.100 [WetLabsBB2FL](INFO): Powering up 2025-03-18T18:30:16.102Z,1742322616.102 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942 2025-03-18T18:30:16.108Z,1742322616.108 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-18T18:30:16.109Z,1742322616.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-18T18:30:16.109Z,1742322616.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-18T18:30:16.109Z,1742322616.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-18T18:30:16.109Z,1742322616.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-18T18:30:16.109Z,1742322616.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-18T18:30:16.110Z,1742322616.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-18T18:30:16.110Z,1742322616.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-18T18:30:16.116Z,1742322616.116 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-18T18:30:16.156Z,1742322616.156 [CBIT](DEBUG): Backplane powered. 2025-03-18T18:30:16.156Z,1742322616.156 [GFScanner](DEBUG): Initializing GFScanner 2025-03-18T18:30:16.157Z,1742322616.157 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-18T18:30:16.157Z,1742322616.157 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-18T18:30:16.157Z,1742322616.157 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-18T18:30:16.158Z,1742322616.158 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-18T18:30:16.158Z,1742322616.158 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-18T18:30:16.159Z,1742322616.159 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-18T18:30:16.160Z,1742322616.160 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-18T18:30:16.164Z,1742322616.164 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-18T18:30:16.165Z,1742322616.165 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-18T18:30:16.165Z,1742322616.165 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-18T18:30:16.170Z,1742322616.170 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-18T18:30:16.243Z,1742322616.243 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-18T18:30:16.247Z,1742322616.247 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-18T18:30:16.297Z,1742322616.297 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2025-03-18T18:30:16.298Z,1742322616.298 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-18T18:30:16.298Z,1742322616.298 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-18T18:30:16.302Z,1742322616.302 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-18T18:30:16.480Z,1742322616.480 [Radio_Surface](INFO): Powering up 2025-03-18T18:30:16.617Z,1742322616.617 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-03-18T18:30:16.639Z,1742322616.639 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-18T18:30:16.648Z,1742322616.648 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-18T18:30:16.650Z,1742322616.650 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-18T18:30:16.687Z,1742322616.687 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-18T18:30:16.705Z,1742322616.705 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-18T18:30:16.720Z,1742322616.720 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-18T18:30:16.723Z,1742322616.723 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2025-03-18T18:30:16.732Z,1742322616.732 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-03-18T18:30:16.744Z,1742322616.744 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-18T18:30:16.905Z,1742322616.905 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2025-03-18T18:30:16.906Z,1742322616.906 [Power24vConverter](INFO): Powering up. 2025-03-18T18:30:16.907Z,1742322616.907 [Sonardyne_Nano](INFO): Initializing. 2025-03-18T18:30:16.960Z,1742322616.960 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-03-18T18:30:16.985Z,1742322616.985 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:30:16.992Z,1742322616.992 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-18T18:30:16.994Z,1742322616.994 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:30:17.000Z,1742322617.000 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-18T18:30:17.001Z,1742322617.001 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:30:17.008Z,1742322617.008 [MassServo](DEBUG): Initializing MassServo. 2025-03-18T18:30:17.009Z,1742322617.009 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:30:17.016Z,1742322617.016 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-18T18:30:17.017Z,1742322617.017 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-18T18:30:17.024Z,1742322617.024 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-18T18:30:17.027Z,1742322617.027 [CBIT](FAULT): Main Battery Failure 2025-03-18T18:30:17.210Z,1742322617.210 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-18T18:30:17.210Z,1742322617.210 [DropWeight] Hardware Fault, FailCount= 1 2025-03-18T18:30:17.210Z,1742322617.210 [DropWeight](ERROR): Hardware Fault 2025-03-18T18:30:17.250Z,1742322617.250 [CommandExec](FAULT): Scheduling is paused 2025-03-18T18:30:17.251Z,1742322617.251 [CBIT](INFO): Critical error at 20250318T183017 2025-03-18T18:30:17.251Z,1742322617.251 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-03-18T18:30:17.262Z,1742322617.262 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-18T18:30:17.263Z,1742322617.263 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-18T18:30:17.592Z,1742322617.592 [CBIT](INFO): Critical error at 20250318T183017 2025-03-18T18:30:18.208Z,1742322618.208 [WetLabsBB2FL](INFO): Powering down 2025-03-18T18:30:19.166Z,1742322619.166 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-03-18T18:30:20.420Z,1742322620.420 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-03-18T18:30:23.124Z,1742322623.124 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-03-18T18:30:29.397Z,1742322629.397 [DAT](INFO): DAT read: 2025-03-18T18:30:29.399Z,1742322629.399 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-03-18T18:30:31.161Z,1742322631.161 [DAT](INFO): DAT read: MF Frequency Band 2025-03-18T18:30:31.162Z,1742322631.162 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-03-18T18:30:31.163Z,1742322631.163 [DAT](INFO): DAT read: Jan 1 2000 00:00:07 2025-03-18T18:30:32.169Z,1742322632.169 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-03-18T18:30:32.170Z,1742322632.170 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-03-18T18:30:32.171Z,1742322632.171 [DAT](INFO): commRate: 800 2025-03-18T18:30:34.236Z,1742322634.236 [DAT](INFO): entering command mode 2025-03-18T18:30:34.436Z,1742322634.436 [DAT](INFO): DAT read: 2025-03-18T18:30:34.437Z,1742322634.437 [DAT](INFO): DAT read: user:1> 2025-03-18T18:30:34.437Z,1742322634.437 [DAT](INFO): setting verbose to 3 2025-03-18T18:30:34.689Z,1742322634.689 [DAT](INFO): DAT read: user:1> 2025-03-18T18:30:34.690Z,1742322634.690 [DAT](INFO): DAT read: Verbose | 3 2025-03-18T18:30:34.690Z,1742322634.690 [DAT](INFO): set verbose to 3 2025-03-18T18:30:34.691Z,1742322634.691 [DAT](INFO): setting DatVerbose to 27440 2025-03-18T18:30:34.941Z,1742322634.941 [DAT](INFO): DAT read: user:2> 2025-03-18T18:30:34.941Z,1742322634.941 [DAT](INFO): DAT read: DatVerbose | 27440 2025-03-18T18:30:34.942Z,1742322634.942 [DAT](INFO): set DatVerbose to 27440 2025-03-18T18:30:34.942Z,1742322634.942 [DAT](INFO): setting transmit power to 8 2025-03-18T18:30:35.193Z,1742322635.193 [DAT](INFO): DAT read: user:3> 2025-03-18T18:30:35.194Z,1742322635.194 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-03-18T18:30:35.194Z,1742322635.194 [DAT](INFO): set transmit power to 8 2025-03-18T18:30:35.194Z,1742322635.194 [DAT](INFO): setting local address to 1 2025-03-18T18:30:35.445Z,1742322635.445 [DAT](INFO): DAT read: user:4> 2025-03-18T18:30:35.446Z,1742322635.446 [DAT](INFO): DAT read: LocalAddr | 1 2025-03-18T18:30:35.447Z,1742322635.447 [DAT](INFO): set local address to 1 2025-03-18T18:30:35.448Z,1742322635.448 [DAT](INFO): Setting time to: 18:30:35 And date to:3/18/2025 2025-03-18T18:30:35.697Z,1742322635.697 [DAT](INFO): DAT read: user:5> 2025-03-18T18:30:35.698Z,1742322635.698 [DAT](INFO): DAT read: Tue Mar 18, 2025 18:30:35 2025-03-18T18:30:35.698Z,1742322635.698 [DAT](INFO): Local DAT time set to Tue Mar 18, 2025 18:30:35 2025-03-18T18:30:44.032Z,1742322644.032 [NAL9602](INFO): Powering up NAL9602 2025-03-18T18:30:53.888Z,1742322653.888 [Waterlinked](INFO): Powering down 2025-03-18T18:30:54.989Z,1742322654.989 [NAL9602](INFO): NAL9602 initialized 2025-03-18T18:31:27.722Z,1742322687.722 [SBIT](IMPORTANT): Beginning Startup BIT 2025-03-18T18:32:21.474Z,1742322741.474 [SBIT](IMPORTANT): SBIT PASSED 2025-03-18T18:32:21.474Z,1742322741.474 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-03-18T18:32:21.854Z,1742322741.854 [MissionManager](IMPORTANT): Started mission Startup 2025-03-18T18:32:21.854Z,1742322741.854 [Startup] Running Loop=1 2025-03-18T18:32:21.854Z,1742322741.854 [Startup](DEBUG): Aggregate::initialize Startup 2025-03-18T18:32:21.854Z,1742322741.854 [Startup:A.GoToSurface] Running Loop=1 2025-03-18T18:32:21.854Z,1742322741.854 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-18T18:32:21.855Z,1742322741.855 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-18T18:32:21.855Z,1742322741.855 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-18T18:32:21.856Z,1742322741.856 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-18T18:32:21.856Z,1742322741.856 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-18T18:32:21.857Z,1742322741.857 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-18T18:32:21.857Z,1742322741.857 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-18T18:32:21.859Z,1742322741.859 [Startup:StartupSatComms] Running Loop=1 2025-03-18T18:32:21.859Z,1742322741.859 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-03-18T18:32:21.859Z,1742322741.859 [Startup:StartupSatComms:A] Running Loop=1 2025-03-18T18:32:22.267Z,1742322742.267 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-03-18T18:33:00.951Z,1742322780.951 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005213 2025-03-18T18:33:16.363Z,1742322796.363 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-03-18T18:33:16.363Z,1742322796.363 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T18:33:16.376Z,1742322796.376 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T18:33:16.782Z,1742322796.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T18:33:16.782Z,1742322796.782 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-03-18T18:33:22.019Z,1742322802.019 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-18T18:32:21.9Z 2025-03-18T18:33:22.019Z,1742322802.019 [Startup:StartupSatComms:A] Stopped 2025-03-18T18:33:22.020Z,1742322802.020 [Startup:StartupSatComms:B] Running Loop=1 2025-03-18T18:33:22.423Z,1742322802.423 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-18T18:33:28.869Z,1742322808.869 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250318T161156/Courier0073.lzma 2025-03-18T18:33:29.288Z,1742322809.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=22217, MT Status=2, MTMSN=0 2025-03-18T18:33:29.288Z,1742322809.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-18T18:33:29.871Z,1742322809.871 [DataOverHttps](INFO): Moved sent file to Logs/20250318T161156/Courier0073.lzma.bak 2025-03-18T18:33:29.871Z,1742322809.871 [DataOverHttps](INFO): SBD MOMSN=24371902 2025-03-18T18:33:45.389Z,1742322825.389 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250318T183004/Courier0000.lzma 2025-03-18T18:33:46.391Z,1742322826.391 [DataOverHttps](INFO): Moved sent file to Logs/20250318T183004/Courier0000.lzma.bak 2025-03-18T18:33:46.391Z,1742322826.391 [DataOverHttps](INFO): SBD MOMSN=24371904 2025-03-18T18:34:01.917Z,1742322841.917 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250318T161156/Express0074.lzma 2025-03-18T18:34:02.919Z,1742322842.919 [DataOverHttps](INFO): Moved sent file to Logs/20250318T161156/Express0074.lzma.bak 2025-03-18T18:34:02.919Z,1742322842.919 [DataOverHttps](INFO): SBD MOMSN=24371909 2025-03-18T18:34:18.442Z,1742322858.442 [DataOverHttps](INFO): Sending 595 bytes from file Logs/20250318T183004/Express0001.lzma 2025-03-18T18:34:19.443Z,1742322859.443 [DataOverHttps](INFO): Moved sent file to Logs/20250318T183004/Express0001.lzma.bak 2025-03-18T18:34:19.443Z,1742322859.443 [DataOverHttps](INFO): SBD MOMSN=24371911 2025-03-18T18:34:21.007Z,1742322861.007 [Startup:StartupSatComms:B] Stopped 2025-03-18T18:34:21.007Z,1742322861.007 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-03-18T18:34:21.007Z,1742322861.007 [Startup:StartupSatComms] Stopped 2025-03-18T18:34:21.007Z,1742322861.007 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-03-18T18:34:21.008Z,1742322861.008 [Startup](INFO): Completed Startup 2025-03-18T18:34:21.009Z,1742322861.009 [MissionManager](INFO): Startup is completed. 2025-03-18T18:34:21.009Z,1742322861.009 [MissionManager](INFO): Uninitializing Mission Startup 2025-03-18T18:34:21.009Z,1742322861.009 [Startup] Stopped 2025-03-18T18:34:21.009Z,1742322861.009 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-03-18T18:34:21.009Z,1742322861.009 [Startup:A.GoToSurface] Stopped 2025-03-18T18:34:21.009Z,1742322861.009 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-18T18:34:21.407Z,1742322861.407 [MissionManager](IMPORTANT): Started mission Default 2025-03-18T18:34:21.407Z,1742322861.407 [Default] Running Loop=1 2025-03-18T18:34:21.407Z,1742322861.407 [Default](DEBUG): Aggregate::initialize Default 2025-03-18T18:34:21.408Z,1742322861.408 [Default:B.GoToSurface] Running Loop=1 2025-03-18T18:34:21.408Z,1742322861.408 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-18T18:34:21.408Z,1742322861.408 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-18T18:34:21.408Z,1742322861.408 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-18T18:34:21.408Z,1742322861.408 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-18T18:34:21.409Z,1742322861.409 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-18T18:34:21.434Z,1742322861.434 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-18T18:34:21.434Z,1742322861.434 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-18T18:34:21.434Z,1742322861.434 [Default:A.Wait] Running Loop=1 2025-03-18T18:34:21.435Z,1742322861.435 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-18T18:34:34.740Z,1742322874.740 [Default:A.Wait](INFO): Done Waiting. 2025-03-18T18:34:34.740Z,1742322874.740 [Default:A.Wait] Stopped 2025-03-18T18:34:34.740Z,1742322874.740 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-18T18:34:35.148Z,1742322875.148 [Default:CheckIn] Running Loop=1 2025-03-18T18:34:35.148Z,1742322875.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-18T18:34:35.148Z,1742322875.148 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-18T18:34:35.548Z,1742322875.548 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-18T18:35:17.146Z,1742322917.146 [BPC1](FAULT): Failed to initialize 2025-03-18T18:35:17.146Z,1742322917.146 [BPC1] Communications Fault, FailCount= 1 2025-03-18T18:35:17.146Z,1742322917.146 [BPC1](ERROR): Communications Fault 2025-03-18T18:35:17.217Z,1742322917.217 [CBIT](ERROR): Communications Fault in component: BPC1 2025-03-18T18:35:57.955Z,1742322957.955 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-18T18:36:17.354Z,1742322977.354 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-03-18T18:36:17.354Z,1742322977.354 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T18:36:17.365Z,1742322977.365 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T18:36:17.775Z,1742322977.775 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T18:36:17.775Z,1742322977.775 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-03-18T18:39:18.346Z,1742323158.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-03-18T18:39:18.346Z,1742323158.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T18:39:18.357Z,1742323158.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T18:39:18.762Z,1742323158.762 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T18:39:18.762Z,1742323158.762 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-03-18T18:39:35.327Z,1742323175.327 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-18T18:34:35.1Z 2025-03-18T18:39:35.327Z,1742323175.327 [Default:CheckIn:Read_GPS] Stopped 2025-03-18T18:39:35.327Z,1742323175.327 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-18T18:39:35.720Z,1742323175.720 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-18T18:39:42.565Z,1742323182.565 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250318T183004/Courier0004.lzma 2025-03-18T18:39:43.567Z,1742323183.567 [DataOverHttps](INFO): Moved sent file to Logs/20250318T183004/Courier0004.lzma.bak 2025-03-18T18:39:43.567Z,1742323183.567 [DataOverHttps](INFO): SBD MOMSN=24371959 2025-03-18T18:39:59.097Z,1742323199.097 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20250318T183004/Express0005.lzma 2025-03-18T18:40:00.100Z,1742323200.100 [DataOverHttps](INFO): Moved sent file to Logs/20250318T183004/Express0005.lzma.bak 2025-03-18T18:40:00.100Z,1742323200.100 [DataOverHttps](INFO): SBD MOMSN=24371961 2025-03-18T18:40:01.599Z,1742323201.599 [Default:CheckIn:Read_Iridium] Stopped 2025-03-18T18:40:01.599Z,1742323201.599 [Default:CheckIn:C.Wait] Runni