2025-03-28T16:22:54.842Z,1743178974.842 [Supervisor](DEBUG): Initializing supervisor. 2025-03-28T16:22:54.846Z,1743178974.846 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-28T16:22:54.846Z,1743178974.846 [SyncHandler](INFO): Protected caller Thread ID is 616 2025-03-28T16:22:54.847Z,1743178974.847 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-28T16:22:54.848Z,1743178974.848 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-28T16:22:54.848Z,1743178974.848 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 617 2025-03-28T16:22:54.852Z,1743178974.852 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-28T16:22:54.872Z,1743178974.872 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-28T16:22:54.873Z,1743178974.873 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-28T16:22:54.873Z,1743178974.873 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 618 2025-03-28T16:22:54.878Z,1743178974.878 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-28T16:22:54.878Z,1743178974.878 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-28T16:22:54.879Z,1743178974.879 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 619 2025-03-28T16:22:54.881Z,1743178974.881 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-28T16:22:54.882Z,1743178974.882 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-28T16:22:54.882Z,1743178974.882 [logger ThreadHandler](INFO): Protected caller Thread ID is 620 2025-03-28T16:22:54.886Z,1743178974.886 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-28T16:22:54.887Z,1743178974.887 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-28T16:22:54.888Z,1743178974.888 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-28T16:22:55.236Z,1743178975.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-28T16:22:55.236Z,1743178975.236 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-28T16:22:55.327Z,1743178975.327 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-28T16:22:55.445Z,1743178975.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-28T16:22:55.445Z,1743178975.445 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-28T16:22:55.585Z,1743178975.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-28T16:22:55.586Z,1743178975.586 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-28T16:22:55.942Z,1743178975.942 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-28T16:22:55.942Z,1743178975.942 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-28T16:22:56.049Z,1743178976.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-28T16:22:56.050Z,1743178976.050 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-28T16:22:56.479Z,1743178976.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-28T16:22:56.480Z,1743178976.480 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-28T16:22:56.716Z,1743178976.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-28T16:22:56.717Z,1743178976.717 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-28T16:22:56.807Z,1743178976.807 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-28T16:22:57.058Z,1743178977.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-28T16:22:57.059Z,1743178977.059 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-28T16:22:57.289Z,1743178977.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-28T16:22:57.289Z,1743178977.289 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-28T16:22:57.826Z,1743178977.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-28T16:22:57.827Z,1743178977.827 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-28T16:22:58.029Z,1743178978.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-28T16:22:58.029Z,1743178978.029 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-28T16:22:58.325Z,1743178978.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-28T16:22:58.325Z,1743178978.325 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-28T16:22:59.200Z,1743178979.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-28T16:22:59.200Z,1743178979.200 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-28T16:22:59.819Z,1743178979.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-28T16:22:59.821Z,1743178979.821 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-03-28T16:22:59.822Z,1743178979.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-03-28T16:22:59.981Z,1743178979.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-03-28T16:23:00.128Z,1743178980.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-03-28T16:23:00.224Z,1743178980.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-03-28T16:23:00.344Z,1743178980.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-03-28T16:23:00.443Z,1743178980.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-03-28T16:23:00.715Z,1743178980.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-28T16:23:00.715Z,1743178980.715 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-03-28T16:23:00.836Z,1743178980.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-03-28T16:23:00.948Z,1743178980.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-03-28T16:23:01.103Z,1743178981.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-03-28T16:23:01.199Z,1743178981.199 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-03-28T16:23:01.222Z,1743178981.222 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-28T16:23:01.357Z,1743178981.357 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-28T16:23:01.357Z,1743178981.357 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-28T16:23:01.411Z,1743178981.411 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-28T16:23:01.472Z,1743178981.472 [VerticalControl] Loaded 2025-03-28T16:23:01.472Z,1743178981.472 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-28T16:23:01.475Z,1743178981.475 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-28T16:23:01.521Z,1743178981.521 [HorizontalControl] Loaded 2025-03-28T16:23:01.521Z,1743178981.521 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-28T16:23:01.524Z,1743178981.524 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-28T16:23:01.527Z,1743178981.527 [SpeedControl] Loaded 2025-03-28T16:23:01.527Z,1743178981.527 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-28T16:23:01.530Z,1743178981.530 [LoopControl](DEBUG): Construct LoopControl. 2025-03-28T16:23:01.530Z,1743178981.530 [LoopControl] Loaded 2025-03-28T16:23:01.531Z,1743178981.531 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-28T16:23:01.531Z,1743178981.531 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-28T16:23:01.532Z,1743178981.532 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-28T16:23:01.591Z,1743178981.591 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-28T16:23:01.592Z,1743178981.592 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-28T16:23:02.066Z,1743178982.066 [AHRS_M2] Loaded 2025-03-28T16:23:02.067Z,1743178982.067 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-28T16:23:02.104Z,1743178982.104 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4 2025-03-28T16:23:02.105Z,1743178982.105 [BackseatComponent] Loaded 2025-03-28T16:23:02.105Z,1743178982.105 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-03-28T16:23:02.106Z,1743178982.106 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-03-28T16:23:02.107Z,1743178982.107 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 700 2025-03-28T16:23:02.110Z,1743178982.110 [LcmUniversalReporter] Loaded 2025-03-28T16:23:02.110Z,1743178982.110 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-03-28T16:23:02.904Z,1743178982.904 [BPC1] Loaded 2025-03-28T16:23:02.904Z,1743178982.904 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-28T16:23:02.990Z,1743178982.990 [DataOverHttps] Loaded 2025-03-28T16:23:02.990Z,1743178982.990 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-28T16:23:02.991Z,1743178982.991 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-03-28T16:23:02.992Z,1743178982.992 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 701 2025-03-28T16:23:03.015Z,1743178983.015 [Depth_Keller] Loaded 2025-03-28T16:23:03.015Z,1743178983.015 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-28T16:23:03.020Z,1743178983.020 [DropWeight] Loaded 2025-03-28T16:23:03.020Z,1743178983.020 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-28T16:23:03.084Z,1743178983.084 [NAL9602] Loaded 2025-03-28T16:23:03.084Z,1743178983.084 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-28T16:23:03.114Z,1743178983.114 [Onboard] Loaded 2025-03-28T16:23:03.114Z,1743178983.114 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-28T16:23:03.115Z,1743178983.115 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-03-28T16:23:03.115Z,1743178983.115 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 702 2025-03-28T16:23:03.129Z,1743178983.129 [Power24vConverter] Loaded 2025-03-28T16:23:03.129Z,1743178983.129 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-28T16:23:03.145Z,1743178983.145 [Radio_Surface] Loaded 2025-03-28T16:23:03.145Z,1743178983.145 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-28T16:23:03.146Z,1743178983.146 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-03-28T16:23:03.147Z,1743178983.147 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 703 2025-03-28T16:23:03.162Z,1743178983.162 [Sonardyne_Nano] Loaded 2025-03-28T16:23:03.163Z,1743178983.163 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-03-28T16:23:03.187Z,1743178983.187 [Waterlinked] Loaded 2025-03-28T16:23:03.187Z,1743178983.187 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-03-28T16:23:03.188Z,1743178983.188 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-28T16:23:03.188Z,1743178983.188 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-28T16:23:03.198Z,1743178983.198 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-28T16:23:03.199Z,1743178983.199 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-28T16:23:03.228Z,1743178983.228 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-28T16:23:03.228Z,1743178983.228 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-28T16:23:03.390Z,1743178983.390 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-28T16:23:03.399Z,1743178983.399 [SBIT] Loaded 2025-03-28T16:23:03.399Z,1743178983.399 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-28T16:23:03.402Z,1743178983.402 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-28T16:23:03.415Z,1743178983.415 [IBIT] Loaded 2025-03-28T16:23:03.415Z,1743178983.415 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-28T16:23:03.420Z,1743178983.420 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-28T16:23:03.499Z,1743178983.499 [CBIT] Loaded 2025-03-28T16:23:03.500Z,1743178983.500 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-28T16:23:03.531Z,1743178983.531 [GFScanner] Loaded 2025-03-28T16:23:03.531Z,1743178983.531 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-28T16:23:03.532Z,1743178983.532 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-28T16:23:03.532Z,1743178983.532 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-28T16:23:03.878Z,1743178983.878 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-28T16:23:03.878Z,1743178983.878 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-28T16:23:03.930Z,1743178983.930 [DepthRateCalculator] Loaded 2025-03-28T16:23:03.930Z,1743178983.930 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-28T16:23:03.935Z,1743178983.935 [PitchRateCalculator] Loaded 2025-03-28T16:23:03.935Z,1743178983.935 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-28T16:23:03.946Z,1743178983.946 [SpeedCalculator] Loaded 2025-03-28T16:23:03.946Z,1743178983.946 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-28T16:23:03.950Z,1743178983.950 [YawRateCalculator] Loaded 2025-03-28T16:23:03.950Z,1743178983.950 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-28T16:23:03.969Z,1743178983.969 [ElevatorOffsetCalculator] Loaded 2025-03-28T16:23:03.969Z,1743178983.969 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-28T16:23:03.970Z,1743178983.970 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-28T16:23:03.970Z,1743178983.970 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-28T16:23:04.151Z,1743178984.151 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-28T16:23:04.151Z,1743178984.151 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-28T16:23:04.265Z,1743178984.265 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-28T16:23:04.265Z,1743178984.265 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-28T16:23:04.279Z,1743178984.279 [NavChart] Loaded 2025-03-28T16:23:04.279Z,1743178984.279 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-28T16:23:04.285Z,1743178984.285 [UniversalFixResidualReporter] Loaded 2025-03-28T16:23:04.285Z,1743178984.285 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-28T16:23:04.286Z,1743178984.286 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-28T16:23:04.286Z,1743178984.286 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-28T16:23:04.423Z,1743178984.423 [BuoyancyServo] Loaded 2025-03-28T16:23:04.424Z,1743178984.424 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-28T16:23:04.447Z,1743178984.447 [ElevatorServo] Loaded 2025-03-28T16:23:04.448Z,1743178984.448 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-28T16:23:04.470Z,1743178984.470 [MassServo] Loaded 2025-03-28T16:23:04.470Z,1743178984.470 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-28T16:23:04.491Z,1743178984.491 [RudderServo] Loaded 2025-03-28T16:23:04.491Z,1743178984.491 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-28T16:23:04.508Z,1743178984.508 [ThrusterHE] Loaded 2025-03-28T16:23:04.508Z,1743178984.508 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-28T16:23:04.509Z,1743178984.509 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-28T16:23:04.509Z,1743178984.509 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-28T16:23:04.677Z,1743178984.677 [CTD_Seabird] Loaded 2025-03-28T16:23:04.678Z,1743178984.678 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-28T16:23:04.679Z,1743178984.679 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD54E0 2025-03-28T16:23:04.679Z,1743178984.679 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 704 2025-03-28T16:23:04.715Z,1743178984.715 [ESPComponent] Loaded 2025-03-28T16:23:04.716Z,1743178984.716 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-03-28T16:23:04.738Z,1743178984.738 [PAR_Licor] Loaded 2025-03-28T16:23:04.738Z,1743178984.738 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-28T16:23:04.739Z,1743178984.739 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-28T16:23:04.745Z,1743178984.745 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-28T16:23:04.748Z,1743178984.748 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-28T16:23:04.759Z,1743178984.759 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-28T16:23:04.760Z,1743178984.760 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0 2025-03-28T16:23:04.761Z,1743178984.761 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 705 2025-03-28T16:23:04.765Z,1743178984.765 [Supervisor](INFO): Main Thread ID is 615 2025-03-28T16:23:04.765Z,1743178984.765 [Supervisor](DEBUG): Running supervisor. 2025-03-28T16:23:04.766Z,1743178984.766 [CommandExec ThreadHandler](INFO): Handler Thread ID is 706 2025-03-28T16:23:04.766Z,1743178984.766 [CommandExec](INFO): Initializing the command executive. 2025-03-28T16:23:04.768Z,1743178984.768 [CommandLine ThreadHandler](INFO): Handler Thread ID is 707 2025-03-28T16:23:04.770Z,1743178984.770 [controlThread ThreadHandler](INFO): Handler Thread ID is 708 2025-03-28T16:23:04.770Z,1743178984.770 [controlThread](DEBUG): Initializing ControlThread 2025-03-28T16:23:04.771Z,1743178984.771 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-28T16:23:04.773Z,1743178984.773 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-28T16:23:04.774Z,1743178984.774 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-28T16:23:04.774Z,1743178984.774 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-28T16:23:04.778Z,1743178984.778 [SBIT](INFO): Initialize SBIT Component. 2025-03-28T16:23:04.778Z,1743178984.778 [SBIT](IMPORTANT): git: 2025-03-12_C-9-g23169a8d0 2025-03-28T16:23:04.778Z,1743178984.778 [SBIT](INFO): git hash: 23169a8d0a8c695b45e6ada508c0af820e9b2bcf 2025-03-28T16:23:04.779Z,1743178984.779 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-28T16:23:04.780Z,1743178984.780 [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-28T16:23:04.781Z,1743178984.781 [SBIT](INFO): Beginning SBIT in 82.000000 seconds. 2025-03-28T16:23:04.782Z,1743178984.782 [IBIT](INFO): Initialize IBIT Component. 2025-03-28T16:23:04.782Z,1743178984.782 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-28T16:23:04.783Z,1743178984.783 [logger ThreadHandler](INFO): Handler Thread ID is 709 2025-03-28T16:23:04.796Z,1743178984.796 [CBIT](DEBUG): Initialized mux pins. 2025-03-28T16:23:04.796Z,1743178984.796 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-28T16:23:04.800Z,1743178984.800 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 710 2025-03-28T16:23:04.808Z,1743178984.808 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 711 2025-03-28T16:23:04.810Z,1743178984.810 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-28T16:23:04.816Z,1743178984.816 [Onboard ThreadHandler](INFO): Handler Thread ID is 712 2025-03-28T16:23:04.820Z,1743178984.820 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-28T16:23:04.821Z,1743178984.821 [CBIT](DEBUG): Initializing heartbeat. 2025-03-28T16:23:04.837Z,1743178984.837 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 713 2025-03-28T16:23:04.856Z,1743178984.856 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 714 2025-03-28T16:23:04.857Z,1743178984.857 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-28T16:23:04.861Z,1743178984.861 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 716 2025-03-28T16:23:04.864Z,1743178984.864 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-28T16:23:04.864Z,1743178984.864 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-28T16:23:04.864Z,1743178984.864 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-28T16:23:04.864Z,1743178984.864 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-28T16:23:04.865Z,1743178984.865 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-28T16:23:04.865Z,1743178984.865 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-28T16:23:04.865Z,1743178984.865 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-28T16:23:04.865Z,1743178984.865 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-28T16:23:04.892Z,1743178984.892 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-28T16:23:04.932Z,1743178984.932 [CBIT](DEBUG): Backplane powered. 2025-03-28T16:23:04.932Z,1743178984.932 [GFScanner](DEBUG): Initializing GFScanner 2025-03-28T16:23:04.932Z,1743178984.932 [GFScanner](DEBUG): Deactivating GF circuits. 2025-03-28T16:23:04.936Z,1743178984.936 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-28T16:23:04.937Z,1743178984.937 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-28T16:23:04.937Z,1743178984.937 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-28T16:23:04.937Z,1743178984.937 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-28T16:23:04.938Z,1743178984.938 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-28T16:23:04.939Z,1743178984.939 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-28T16:23:04.939Z,1743178984.939 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-28T16:23:04.948Z,1743178984.948 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-28T16:23:04.949Z,1743178984.949 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-28T16:23:04.949Z,1743178984.949 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-28T16:23:04.950Z,1743178984.950 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-28T16:23:05.035Z,1743178985.035 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-28T16:23:05.067Z,1743178985.067 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-28T16:23:05.088Z,1743178985.088 [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-28T16:23:05.089Z,1743178985.089 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-28T16:23:05.089Z,1743178985.089 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-28T16:23:05.090Z,1743178985.090 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-28T16:23:05.240Z,1743178985.240 [Radio_Surface](INFO): Powering up 2025-03-28T16:23:05.429Z,1743178985.429 [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-28T16:23:05.444Z,1743178985.444 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-28T16:23:05.445Z,1743178985.445 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-28T16:23:05.456Z,1743178985.456 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-28T16:23:05.480Z,1743178985.480 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-28T16:23:05.483Z,1743178985.483 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-28T16:23:05.520Z,1743178985.520 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-28T16:23:05.523Z,1743178985.523 [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-28T16:23:05.528Z,1743178985.528 [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-28T16:23:05.548Z,1743178985.548 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-28T16:23:05.619Z,1743178985.619 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2025-03-28T16:23:05.620Z,1743178985.620 [Power24vConverter](INFO): Powering up. 2025-03-28T16:23:05.620Z,1743178985.620 [Sonardyne_Nano](INFO): Initializing. 2025-03-28T16:23:05.692Z,1743178985.692 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-03-28T16:23:05.718Z,1743178985.718 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-28T16:23:05.724Z,1743178985.724 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-28T16:23:05.725Z,1743178985.725 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-28T16:23:05.732Z,1743178985.732 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-28T16:23:05.733Z,1743178985.733 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-28T16:23:05.740Z,1743178985.740 [MassServo](DEBUG): Initializing MassServo. 2025-03-28T16:23:05.741Z,1743178985.741 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-28T16:23:05.748Z,1743178985.748 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-28T16:23:05.749Z,1743178985.749 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-28T16:23:05.756Z,1743178985.756 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-28T16:23:05.759Z,1743178985.759 [CBIT](FAULT): Main Battery Failure 2025-03-28T16:23:05.985Z,1743178985.985 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-28T16:23:05.985Z,1743178985.985 [DropWeight] Hardware Fault, FailCount= 1 2025-03-28T16:23:05.985Z,1743178985.985 [DropWeight](ERROR): Hardware Fault 2025-03-28T16:23:05.998Z,1743178985.998 [CommandExec](FAULT): Scheduling is paused 2025-03-28T16:23:05.998Z,1743178985.998 [CBIT](INFO): Critical error at 20250328T162305 2025-03-28T16:23:05.999Z,1743178985.999 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-03-28T16:23:06.009Z,1743178986.009 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-28T16:23:06.010Z,1743178986.010 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-28T16:23:06.365Z,1743178986.365 [CBIT](INFO): Critical error at 20250328T162306 2025-03-28T16:23:07.558Z,1743178987.558 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-03-28T16:23:08.844Z,1743178988.844 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-03-28T16:23:11.461Z,1743178991.461 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-03-28T16:23:17.642Z,1743178997.642 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005092 2025-03-28T16:23:33.453Z,1743179013.453 [NAL9602](INFO): Powering up NAL9602 2025-03-28T16:23:42.724Z,1743179022.724 [Waterlinked](INFO): Powering down 2025-03-28T16:23:44.625Z,1743179024.625 [NAL9602](INFO): NAL9602 initialized 2025-03-28T16:24:27.466Z,1743179067.466 [SBIT](IMPORTANT): Beginning Startup BIT 2025-03-28T16:24:27.471Z,1743179067.471 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-03-28T16:24:32.115Z,1743179072.115 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.115363 CHAN A1 (24V): 0.000153 CHAN A2 (12V): 0.000449 CHAN A3 (5V): -0.001268 CHAN B0 (3.3V): 0.000670 CHAN B1 (3.15aV): -0.000228 CHAN B2 (3.15bV): 0.000190 CHAN B3 (GND): -0.000339 OPEN: 0.006685 Full Scale: +/- 1 mA 2025-03-28T16:25:21.194Z,1743179121.194 [SBIT](IMPORTANT): SBIT PASSED 2025-03-28T16:25:21.195Z,1743179121.195 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count; 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): CBIT.abortDepth=305 meter; 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter; 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute; 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): Express linearApproximation PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter; 2025-03-28T16:25:21.196Z,1743179121.196 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 1.000000 count_per_second; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 25.000000 count_per_second; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): Express none _.planktivore_diatoms; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): Express none _.planktivore_dinoflagellates; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): Express none _.predict_plankton_data_pct_dinos_sqerr; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter; 2025-03-28T16:25:21.197Z,1743179121.197 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): MassServo.loadAtStartup=1 bool; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): Normal all _.planktivore_diatoms; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): Normal all _.planktivore_dinoflagellates; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): Normal linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=0 bool; 2025-03-28T16:25:21.198Z,1743179121.198 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=286.339339 cubic_centimeter; 2025-03-28T16:25:21.199Z,1743179121.199 [SBIT](IMPORTANT): VerticalControl.massDefault=15.558109 millimeter; 2025-03-28T16:25:21.199Z,1743179121.199 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool; 2025-03-28T16:25:21.607Z,1743179121.607 [MissionManager](IMPORTANT): Started mission Startup 2025-03-28T16:25:21.607Z,1743179121.607 [Startup] Running Loop=1 2025-03-28T16:25:21.607Z,1743179121.607 [Startup](DEBUG): Aggregate::initialize Startup 2025-03-28T16:25:21.607Z,1743179121.607 [Startup:A.GoToSurface] Running Loop=1 2025-03-28T16:25:21.607Z,1743179121.607 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-28T16:25:21.608Z,1743179121.608 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-28T16:25:21.608Z,1743179121.608 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-28T16:25:21.609Z,1743179121.609 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-28T16:25:21.609Z,1743179121.609 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-28T16:25:21.609Z,1743179121.609 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-28T16:25:21.610Z,1743179121.610 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-28T16:25:21.618Z,1743179121.618 [Startup:StartupSatComms] Running Loop=1 2025-03-28T16:25:21.618Z,1743179121.618 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-03-28T16:25:21.618Z,1743179121.618 [Startup:StartupSatComms:A] Running Loop=1 2025-03-28T16:25:22.034Z,1743179122.034 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-03-28T16:26:05.257Z,1743179165.257 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-03-28T16:26:05.257Z,1743179165.257 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-28T16:26:05.268Z,1743179165.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-28T16:26:05.643Z,1743179165.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-28T16:26:05.643Z,1743179165.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-03-28T16:26:21.797Z,1743179181.797 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-28T16:25:21.6Z 2025-03-28T16:26:21.798Z,1743179181.798 [Startup:StartupSatComms:A] Stopped 2025-03-28T16:26:21.798Z,1743179181.798 [Startup:StartupSatComms:B] Running Loop=1 2025-03-28T16:26:22.202Z,1743179182.202 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-28T16:26:28.665Z,1743179188.665 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250320T210546/Courier0082.lzma 2025-03-28T16:26:29.666Z,1743179189.666 [DataOverHttps](INFO): Moved sent file to Logs/20250320T210546/Courier0082.lzma.bak 2025-03-28T16:26:29.666Z,1743179189.666 [DataOverHttps](INFO): SBD MOMSN=24476274 2025-03-28T16:26:45.193Z,1743179205.193 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20250325T003128/Courier0000.lzma 2025-03-28T16:26:46.193Z,1743179206.193 [DataOverHttps](INFO): Moved sent file to Logs/20250325T003128/Courier0000.lzma.bak 2025-03-28T16:26:46.194Z,1743179206.194 [DataOverHttps](INFO): SBD MOMSN=24476279 2025-03-28T16:27:01.905Z,1743179221.905 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250328T155351/Courier0000.lzma 2025-03-28T16:27:02.905Z,1743179222.905 [DataOverHttps](INFO): Moved sent file to Logs/20250328T155351/Courier0000.lzma.bak 2025-03-28T16:27:02.906Z,1743179222.906 [DataOverHttps](INFO): SBD MOMSN=24476284 2025-03-28T16:27:18.496Z,1743179238.496 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250328T161542/Courier0000.lzma 2025-03-28T16:27:19.497Z,1743179239.497 [DataOverHttps](INFO): Moved sent file to Logs/20250328T161542/Courier0000.lzma.bak 2025-03-28T16:27:19.498Z,1743179239.498 [DataOverHttps](INFO): SBD MOMSN=24476289 2025-03-28T16:27:22.115Z,1743179242.115 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-28T16:26:21.8Z 2025-03-28T16:27:22.115Z,1743179242.115 [Startup:StartupSatComms:B] Stopped 2025-03-28T16:27:22.115Z,1743179242.115 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-03-28T16:27:22.115Z,1743179242.115 [Startup:StartupSatComms] Stopped 2025-03-28T16:27:22.115Z,1743179242.115 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-03-28T16:27:22.116Z,1743179242.116 [Startup](INFO): Completed Startup 2025-03-28T16:27:22.116Z,1743179242.116 [MissionManager](INFO): Startup is completed. 2025-03-28T16:27:22.117Z,1743179242.117 [MissionManager](INFO): Uninitializing Mission Startup 2025-03-28T16:27:22.117Z,1743179242.117 [Startup] Stopped 2025-03-28T16:27:22.117Z,1743179242.117 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-03-28T16:27:22.117Z,1743179242.117 [Startup:A.GoToSurface] Stopped 2025-03-28T16:27:22.117Z,1743179242.117 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-28T16:27:22.553Z,1743179242.553 [MissionManager](IMPORTANT): Started mission Default 2025-03-28T16:27:22.553Z,1743179242.553 [Default] Running Loop=1 2025-03-28T16:27:22.553Z,1743179242.553 [Default](DEBUG): Aggregate::initialize Default 2025-03-28T16:27:22.553Z,1743179242.553 [Default:B.GoToSurface] Running Loop=1 2025-03-28T16:27:22.554Z,1743179242.554 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-28T16:27:22.554Z,1743179242.554 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-28T16:27:22.554Z,1743179242.554 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-28T16:27:22.554Z,1743179242.554 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-28T16:27:22.555Z,1743179242.555 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-28T16:27:22.555Z,1743179242.555 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-28T16:27:22.555Z,1743179242.555 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-28T16:27:22.560Z,1743179242.560 [Default:A.Wait] Running Loop=1 2025-03-28T16:27:22.560Z,1743179242.560 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-28T16:27:35.228Z,1743179255.228 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20250328T162254/Courier0000.lzma 2025-03-28T16:27:35.846Z,1743179255.846 [Default:A.Wait](INFO): Done Waiting. 2025-03-28T16:27:35.846Z,1743179255.846 [Default:A.Wait] Stopped 2025-03-28T16:27:35.846Z,1743179255.846 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-28T16:27:36.229Z,1743179256.229 [DataOverHttps](INFO): Moved sent file to Logs/20250328T162254/Courier0000.lzma.bak 2025-03-28T16:27:36.230Z,1743179256.230 [DataOverHttps](INFO): SBD MOMSN=24476294 2025-03-28T16:27:36.276Z,1743179256.276 [Default:CheckIn] Running Loop=1 2025-03-28T16:27:36.276Z,1743179256.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-28T16:27:36.277Z,1743179256.277 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-28T16:27:36.654Z,1743179256.654 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-28T16:28:05.726Z,1743179285.726 [BPC1](FAULT): Failed to initialize 2025-03-28T16:28:05.726Z,1743179285.726 [BPC1] Communications Fault, FailCount= 1 2025-03-28T16:28:05.726Z,1743179285.726 [BPC1](ERROR): Communications Fault 2025-03-28T16:28:05.784Z,1743179285.784 [CBIT](ERROR): Communications Fault in component: BPC1 2025-03-28T16:28:48.159Z,1743179328.159 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-28T16:29:06.359Z,1743179346.359 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-03-28T16:29:06.359Z,1743179346.359 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-28T16:29:06.370Z,1743179346.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-28T16:29:06.767Z,1743179346.767 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-28T16:29:06.767Z,1743179346.767 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-03-28T16:32:07.416Z,1743179527.416 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-03-28T16:32:07.416Z,1743179527.416 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-28T16:32:07.463Z,1743179527.463 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-28T16:32:07.829Z,1743179527.829 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-28T16:32:07.829Z,1743179527.829 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-03-28T16:32:36.490Z,1743179556.490 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-28T16:27:36.3Z 2025-03-28T16:32:36.490Z,1743179556.490 [Default:CheckIn:Read_GPS] Stopped 2025-03-28T16:32:36.490Z,1743179556.490 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-28T16:32:36.905Z,1743179556.905 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-28T16:32:38.756Z,1743179558.756 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250328T162254/Courier0004.lzma 2025-03-28T16:32:39.473Z,1743179559.473 [DataOverHttps](INFO): Moved sent file to Logs/20250328T162254/Courier0004.lzma.bak 2025-03-28T16:32:39.474Z,1743179559.474 [DataOverHttps](INFO): SBD MOMSN=24476307 2025-03-28T16:32:55.037Z,1743179575.037 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250320T210546/Express0083.lzma 2025-03-28T16:32:56.038Z,1743179576.038 [DataOverHttps](INFO): Moved sent file to Logs/20250320T210546/Express0083.lzma.bak 2025-03-28T16:32:56.038Z,1743179576.038 [DataOverHttps](INFO): SBD MOMSN=24476309 2025-03-28T16:33:11.726Z,1743179591.726 [DataOverHttps](INFO): Sending 578 bytes from file Logs/20250325T003128/Express0001.lzma 2025-03-28T16:33:12.725Z,1743179592.725 [DataOverHttps](INFO): Moved sent file to Logs/20250325T003128/Express0001.lzma.bak 2025-03-28T16:33:12.726Z,1743179592.726 [DataOverHttps](INFO): SBD MOMSN=24476312 2025-03-28T16:33:28.330Z,1743179608.330 [DataOverHttps](INFO): Sending 779 bytes from file Logs/20250328T155351/Express0001.lzma 2025-03-28T16:33:29.329Z,1743179609.329 [DataOverHttps](INFO): Moved sent file to Logs/20250328T155351/Express0001.lzma.bak 2025-03-28T16:33:29.330Z,1743179609.330 [DataOverHttps](INFO): SBD MOMSN=24476322 2025-03-28T16:33:43.588Z,1743179623.588 [Power24vConverter](INFO): Powering down. 2025-03-28T16:33:44.056Z,1743179624.056 [DataOverHttps](INFO): Sending 988 bytes from file Logs/20250328T161542/Express0001.lzma 2025-03-28T16:33:45.049Z,1743179625.049 [DataOverHttps](INFO): Moved sent file to Logs/20250328T161542/Express0001.lzma.bak 2025-03-28T16:33:45.050Z,1743179625.050 [DataOverHttps](INFO): SBD MOMSN=24476335 2025-03-28T16:33:46.405Z,1743179626.405 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-28T16:33:46.405Z,1743179626.405 [NAL9602] Data Fault, FailCount= 1 2025-03-28T16:33:46.405Z,1743179626.405 [NAL9602](ERROR): Data Fault 2025-03-28T16:33:46.496Z,1743179626.496 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-28T16:33:46.809Z,1743179626.809 [NAL9602](INFO): Powering down 2025-03-28T16:33:47.658Z,1743179627.658 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-28T16:33:47.658Z,1743179627.658 [NAL9602] No Fault, FailCount= 1 2025-03-28T16:33:53.801Z,1743179633.801 [CommandExec](IMPORTANT): got command quit 2025-03-28T16:33:54.804Z,1743179634.804 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:33:54.804Z,1743179634.804 [CommandExec](INFO): Uninitializing the command executive. 2025-03-28T16:33:54.804Z,1743179634.804 [CommandExec](INFO): Uninitializing the command scheduler. 2025-03-28T16:33:54.804Z,1743179634.804 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:54.821Z,1743179634.821 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-03-28T16:33:54.821Z,1743179634.821 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-03-28T16:33:54.821Z,1743179634.821 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:54.822Z,1743179634.822 [NavChartDb](INFO): Join timeout helper Thread ID is 737 2025-03-28T16:33:54.996Z,1743179634.996 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:33:54.996Z,1743179634.996 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:55.016Z,1743179635.016 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-03-28T16:33:55.016Z,1743179635.016 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:55.016Z,1743179635.016 [CTD_Seabird](INFO): Join timeout helper Thread ID is 738 2025-03-28T16:33:55.528Z,1743179635.528 [CTD_Seabird](INFO): Powering down 2025-03-28T16:33:55.540Z,1743179635.540 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:33:55.540Z,1743179635.540 [CTD_Seabird](INFO): Powering down 2025-03-28T16:33:55.563Z,1743179635.563 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:55.580Z,1743179635.580 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-03-28T16:33:55.580Z,1743179635.580 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:55.580Z,1743179635.580 [Radio_Surface](INFO): Join timeout helper Thread ID is 739 2025-03-28T16:33:55.808Z,1743179635.808 [Radio_Surface](INFO): Powering down 2025-03-28T16:33:55.809Z,1743179635.809 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:33:55.809Z,1743179635.809 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:55.820Z,1743179635.820 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-03-28T16:33:55.820Z,1743179635.820 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:33:55.820Z,1743179635.820 [Onboard](INFO): Join timeout helper Thread ID is 740 2025-03-28T16:34:00.404Z,1743179640.404 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:34:00.404Z,1743179640.404 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.412Z,1743179640.412 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-03-28T16:34:00.412Z,1743179640.412 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.412Z,1743179640.412 [DataOverHttps](INFO): Join timeout helper Thread ID is 741 2025-03-28T16:34:00.472Z,1743179640.472 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:34:00.475Z,1743179640.475 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.492Z,1743179640.492 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-03-28T16:34:00.493Z,1743179640.493 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.493Z,1743179640.493 [BackseatComponent](INFO): Join timeout helper Thread ID is 742 2025-03-28T16:34:00.540Z,1743179640.540 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:34:00.540Z,1743179640.540 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.556Z,1743179640.556 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-03-28T16:34:00.557Z,1743179640.557 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.557Z,1743179640.557 [logger](INFO): Join timeout helper Thread ID is 743 2025-03-28T16:34:00.564Z,1743179640.564 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:34:00.564Z,1743179640.564 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.580Z,1743179640.580 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-03-28T16:34:00.580Z,1743179640.580 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.581Z,1743179640.581 [CommandLine](INFO): Join timeout helper Thread ID is 744 2025-03-28T16:34:00.616Z,1743179640.616 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:34:00.616Z,1743179640.616 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.620Z,1743179640.620 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-03-28T16:34:00.621Z,1743179640.621 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.621Z,1743179640.621 [CommandExec](INFO): Join timeout helper Thread ID is 745 2025-03-28T16:34:00.622Z,1743179640.622 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-03-28T16:34:00.622Z,1743179640.622 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:00.623Z,1743179640.623 [controlThread](INFO): Join timeout helper Thread ID is 746 2025-03-28T16:34:00.936Z,1743179640.936 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-28T16:34:00.936Z,1743179640.936 [controlThread](DEBUG): Uninitializing ControlThread 2025-03-28T16:34:00.936Z,1743179640.936 [AHRS_M2](INFO): Powering down 2025-03-28T16:34:01.008Z,1743179641.008 [NAL9602](INFO): Powering down 2025-03-28T16:34:01.010Z,1743179641.010 [Sonardyne_Nano](INFO): Powering down 2025-03-28T16:34:01.128Z,1743179641.128 [Waterlinked](INFO): Powering down 2025-03-28T16:34:01.131Z,1743179641.131 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-03-28T16:34:01.131Z,1743179641.131 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-03-28T16:34:01.132Z,1743179641.132 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-03-28T16:34:01.133Z,1743179641.133 [MissionManager](INFO): Uninitializing Mission Default 2025-03-28T16:34:01.133Z,1743179641.133 [Default] Stopped 2025-03-28T16:34:01.133Z,1743179641.133 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-28T16:34:01.133Z,1743179641.133 [Default:B.GoToSurface] Stopped 2025-03-28T16:34:01.133Z,1743179641.133 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-28T16:34:01.133Z,1743179641.133 [Default:CheckIn] Stopped 2025-03-28T16:34:01.133Z,1743179641.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-28T16:34:01.133Z,1743179641.133 [Default:CheckIn:Read_Iridium] Stopped 2025-03-28T16:34:01.136Z,1743179641.136 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-03-28T16:34:01.136Z,1743179641.136 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-03-28T16:34:01.137Z,1743179641.137 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-03-28T16:34:01.137Z,1743179641.137 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-03-28T16:34:01.137Z,1743179641.137 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-03-28T16:34:01.138Z,1743179641.138 [BuoyancyServo](INFO): Powering down 2025-03-28T16:34:01.152Z,1743179641.152 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-28T16:34:01.152Z,1743179641.152 [ElevatorServo](INFO): Powering down 2025-03-28T16:34:01.153Z,1743179641.153 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-28T16:34:01.153Z,1743179641.153 [MassServo](INFO): Powering down 2025-03-28T16:34:01.154Z,1743179641.154 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-03-28T16:34:01.154Z,1743179641.154 [RudderServo](INFO): Powering down 2025-03-28T16:34:01.155Z,1743179641.155 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-28T16:34:01.155Z,1743179641.155 [ThrusterHE](INFO): Powering down 2025-03-28T16:34:01.156Z,1743179641.156 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-03-28T16:34:01.156Z,1743179641.156 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-03-28T16:34:01.156Z,1743179641.156 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-03-28T16:34:01.156Z,1743179641.156 [CBIT](DEBUG): Powering off loads. 2025-03-28T16:34:01.167Z,1743179641.167 [CBIT](DEBUG): Disabling WDT. 2025-03-28T16:34:01.180Z,1743179641.180 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-28T16:34:01.180Z,1743179641.180 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-28T16:34:01.181Z,1743179641.181 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:01.223Z,1743179641.223 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:01.226Z,1743179641.226 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:01.232Z,1743179641.232 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:01.275Z,1743179641.275 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:01.309Z,1743179641.309 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-28T16:34:01.309Z,1743179641.309 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-28T16:34:01.368Z,1743179641.368 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:01.376Z,1743179641.376 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-28T16:34:01.446Z,1743179641.446 [logger ThreadHandler](INFO): Thread cancelled.