2025-04-07T15:21:51.918Z,1744039311.918 [Supervisor](DEBUG): Initializing supervisor. 2025-04-07T15:21:51.922Z,1744039311.922 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-04-07T15:21:51.923Z,1744039311.923 [SyncHandler](INFO): Protected caller Thread ID is 5571 2025-04-07T15:21:51.923Z,1744039311.923 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-04-07T15:21:51.924Z,1744039311.924 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-04-07T15:21:51.924Z,1744039311.924 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5572 2025-04-07T15:21:51.929Z,1744039311.929 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-04-07T15:21:51.949Z,1744039311.949 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-04-07T15:21:51.950Z,1744039311.950 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-04-07T15:21:51.950Z,1744039311.950 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5573 2025-04-07T15:21:51.954Z,1744039311.954 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-04-07T15:21:51.955Z,1744039311.955 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-04-07T15:21:51.956Z,1744039311.956 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5574 2025-04-07T15:21:51.958Z,1744039311.958 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-04-07T15:21:51.959Z,1744039311.959 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-04-07T15:21:51.959Z,1744039311.959 [logger ThreadHandler](INFO): Protected caller Thread ID is 5575 2025-04-07T15:21:51.964Z,1744039311.964 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-04-07T15:21:51.964Z,1744039311.964 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-04-07T15:21:51.967Z,1744039311.967 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-04-07T15:21:52.323Z,1744039312.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-04-07T15:21:52.324Z,1744039312.324 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-04-07T15:21:52.419Z,1744039312.419 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-04-07T15:21:52.992Z,1744039312.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-04-07T15:21:52.994Z,1744039312.994 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-04-07T15:21:53.373Z,1744039313.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-04-07T15:21:53.374Z,1744039313.374 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-04-07T15:21:53.485Z,1744039313.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-04-07T15:21:53.487Z,1744039313.487 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-04-07T15:21:54.032Z,1744039314.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-04-07T15:21:54.033Z,1744039314.033 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-04-07T15:21:54.285Z,1744039314.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-04-07T15:21:54.287Z,1744039314.287 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-04-07T15:21:54.849Z,1744039314.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-04-07T15:21:54.849Z,1744039314.849 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-04-07T15:21:55.003Z,1744039315.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-04-07T15:21:55.005Z,1744039315.005 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-04-07T15:21:55.309Z,1744039315.309 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-04-07T15:21:56.268Z,1744039316.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-04-07T15:21:56.268Z,1744039316.268 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-04-07T15:21:56.676Z,1744039316.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-04-07T15:21:56.677Z,1744039316.677 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-04-07T15:21:56.888Z,1744039316.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-04-07T15:21:56.889Z,1744039316.889 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-04-07T15:21:57.016Z,1744039317.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-04-07T15:21:57.016Z,1744039317.016 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-04-07T15:21:57.270Z,1744039317.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-04-07T15:21:57.271Z,1744039317.271 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-04-07T15:21:57.544Z,1744039317.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-04-07T15:21:57.546Z,1744039317.546 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2025-04-07T15:21:57.547Z,1744039317.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2025-04-07T15:21:57.648Z,1744039317.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2025-04-07T15:21:57.764Z,1744039317.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2025-04-07T15:21:57.861Z,1744039317.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2025-04-07T15:21:57.979Z,1744039317.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2025-04-07T15:21:58.087Z,1744039318.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2025-04-07T15:21:58.220Z,1744039318.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2025-04-07T15:21:58.394Z,1744039318.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2025-04-07T15:21:58.563Z,1744039318.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2025-04-07T15:21:58.688Z,1744039318.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2025-04-07T15:21:58.971Z,1744039318.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-04-07T15:21:58.972Z,1744039318.972 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2025-04-07T15:21:58.973Z,1744039318.973 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-04-07T15:21:58.981Z,1744039318.981 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-04-07T15:21:59.090Z,1744039319.090 [VerticalControl](DEBUG): Construct VerticalControl. 2025-04-07T15:21:59.154Z,1744039319.154 [VerticalControl] Loaded 2025-04-07T15:21:59.154Z,1744039319.154 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-04-07T15:21:59.157Z,1744039319.157 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-04-07T15:21:59.205Z,1744039319.205 [HorizontalControl] Loaded 2025-04-07T15:21:59.205Z,1744039319.205 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-04-07T15:21:59.208Z,1744039319.208 [SpeedControl](DEBUG): Construct SpeedControl. 2025-04-07T15:21:59.211Z,1744039319.211 [SpeedControl] Loaded 2025-04-07T15:21:59.211Z,1744039319.211 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-04-07T15:21:59.214Z,1744039319.214 [LoopControl](DEBUG): Construct LoopControl. 2025-04-07T15:21:59.215Z,1744039319.215 [LoopControl] Loaded 2025-04-07T15:21:59.215Z,1744039319.215 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-04-07T15:21:59.215Z,1744039319.215 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-04-07T15:21:59.216Z,1744039319.216 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-04-07T15:21:59.232Z,1744039319.232 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-04-07T15:21:59.233Z,1744039319.233 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-04-07T15:21:59.445Z,1744039319.445 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-04-07T15:21:59.446Z,1744039319.446 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-04-07T15:21:59.611Z,1744039319.611 [BuoyancyServo] Loaded 2025-04-07T15:21:59.612Z,1744039319.612 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-04-07T15:21:59.634Z,1744039319.634 [ElevatorServo] Loaded 2025-04-07T15:21:59.635Z,1744039319.635 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-04-07T15:21:59.657Z,1744039319.657 [MassServo] Loaded 2025-04-07T15:21:59.657Z,1744039319.657 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-04-07T15:21:59.678Z,1744039319.678 [RudderServo] Loaded 2025-04-07T15:21:59.678Z,1744039319.678 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-04-07T15:21:59.695Z,1744039319.695 [ThrusterHE] Loaded 2025-04-07T15:21:59.696Z,1744039319.696 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-04-07T15:21:59.696Z,1744039319.696 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-04-07T15:21:59.697Z,1744039319.697 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-04-07T15:21:59.841Z,1744039319.841 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-04-07T15:21:59.841Z,1744039319.841 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-04-07T15:21:59.855Z,1744039319.855 [NavChart] Loaded 2025-04-07T15:21:59.855Z,1744039319.855 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-04-07T15:21:59.861Z,1744039319.861 [UniversalFixResidualReporter] Loaded 2025-04-07T15:21:59.862Z,1744039319.862 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-04-07T15:21:59.862Z,1744039319.862 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-04-07T15:21:59.863Z,1744039319.863 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-04-07T15:21:59.953Z,1744039319.953 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-04-07T15:21:59.954Z,1744039319.954 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-04-07T15:22:00.880Z,1744039320.880 [AHRS_M2] Loaded 2025-04-07T15:22:00.880Z,1744039320.880 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-04-07T15:22:02.024Z,1744039322.024 [BPC1] Loaded 2025-04-07T15:22:02.024Z,1744039322.024 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-04-07T15:22:02.125Z,1744039322.125 [DAT] Loaded 2025-04-07T15:22:02.126Z,1744039322.126 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-04-07T15:22:02.127Z,1744039322.127 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094B4E0 2025-04-07T15:22:02.127Z,1744039322.127 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5655 2025-04-07T15:22:02.223Z,1744039322.223 [DataOverHttps] Loaded 2025-04-07T15:22:02.223Z,1744039322.223 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-04-07T15:22:02.224Z,1744039322.224 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097B4E0 2025-04-07T15:22:02.225Z,1744039322.225 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5656 2025-04-07T15:22:02.248Z,1744039322.248 [Depth_Keller] Loaded 2025-04-07T15:22:02.248Z,1744039322.248 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-04-07T15:22:02.253Z,1744039322.253 [DropWeight] Loaded 2025-04-07T15:22:02.254Z,1744039322.254 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-04-07T15:22:02.320Z,1744039322.320 [NAL9602] Loaded 2025-04-07T15:22:02.320Z,1744039322.320 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-04-07T15:22:02.351Z,1744039322.351 [Onboard] Loaded 2025-04-07T15:22:02.351Z,1744039322.351 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-04-07T15:22:02.352Z,1744039322.352 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0 2025-04-07T15:22:02.352Z,1744039322.352 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5657 2025-04-07T15:22:02.367Z,1744039322.367 [Power24vConverter] Loaded 2025-04-07T15:22:02.367Z,1744039322.367 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-04-07T15:22:02.383Z,1744039322.383 [Radio_Surface] Loaded 2025-04-07T15:22:02.384Z,1744039322.384 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-04-07T15:22:02.385Z,1744039322.385 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0 2025-04-07T15:22:02.385Z,1744039322.385 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5658 2025-04-07T15:22:02.401Z,1744039322.401 [Sonardyne_Nano] Loaded 2025-04-07T15:22:02.401Z,1744039322.401 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-04-07T15:22:02.402Z,1744039322.402 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-04-07T15:22:02.402Z,1744039322.402 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-04-07T15:22:02.475Z,1744039322.475 [DepthRateCalculator] Loaded 2025-04-07T15:22:02.475Z,1744039322.475 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-04-07T15:22:02.480Z,1744039322.480 [PitchRateCalculator] Loaded 2025-04-07T15:22:02.480Z,1744039322.480 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-04-07T15:22:02.490Z,1744039322.490 [SpeedCalculator] Loaded 2025-04-07T15:22:02.490Z,1744039322.490 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-04-07T15:22:02.494Z,1744039322.494 [YawRateCalculator] Loaded 2025-04-07T15:22:02.495Z,1744039322.495 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-04-07T15:22:02.514Z,1744039322.514 [ElevatorOffsetCalculator] Loaded 2025-04-07T15:22:02.514Z,1744039322.514 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-04-07T15:22:02.515Z,1744039322.515 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-04-07T15:22:02.515Z,1744039322.515 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-04-07T15:22:02.699Z,1744039322.699 [CANONSampler] Loaded 2025-04-07T15:22:02.699Z,1744039322.699 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2025-04-07T15:22:02.765Z,1744039322.765 [CTD_Seabird] Loaded 2025-04-07T15:22:02.766Z,1744039322.766 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-04-07T15:22:02.767Z,1744039322.767 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A964E0 2025-04-07T15:22:02.767Z,1744039322.767 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5659 2025-04-07T15:22:02.790Z,1744039322.790 [PAR_Licor] Loaded 2025-04-07T15:22:02.790Z,1744039322.790 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-04-07T15:22:02.838Z,1744039322.838 [WetLabsBB2FL] Loaded 2025-04-07T15:22:02.839Z,1744039322.839 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-04-07T15:22:02.840Z,1744039322.840 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC64E0 2025-04-07T15:22:02.840Z,1744039322.840 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5660 2025-04-07T15:22:02.841Z,1744039322.841 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-04-07T15:22:02.841Z,1744039322.841 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-04-07T15:22:02.891Z,1744039322.891 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-04-07T15:22:02.891Z,1744039322.891 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-04-07T15:22:03.309Z,1744039323.309 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-04-07T15:22:03.309Z,1744039323.309 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-04-07T15:22:03.511Z,1744039323.511 [SBIT](DEBUG): Construct Startup Built In Test. 2025-04-07T15:22:03.520Z,1744039323.520 [SBIT] Loaded 2025-04-07T15:22:03.521Z,1744039323.521 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-04-07T15:22:03.524Z,1744039323.524 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-04-07T15:22:03.537Z,1744039323.537 [IBIT] Loaded 2025-04-07T15:22:03.537Z,1744039323.537 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-04-07T15:22:03.543Z,1744039323.543 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-04-07T15:22:03.627Z,1744039323.627 [CBIT] Loaded 2025-04-07T15:22:03.627Z,1744039323.627 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-04-07T15:22:03.660Z,1744039323.660 [GFScanner] Loaded 2025-04-07T15:22:03.660Z,1744039323.661 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-04-07T15:22:03.661Z,1744039323.661 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-04-07T15:22:03.661Z,1744039323.661 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-04-07T15:22:03.827Z,1744039323.827 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-04-07T15:22:03.834Z,1744039323.834 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-04-07T15:22:03.837Z,1744039323.837 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-04-07T15:22:03.848Z,1744039323.848 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-04-07T15:22:03.849Z,1744039323.849 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-04-07T15:22:03.850Z,1744039323.850 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5661 2025-04-07T15:22:03.855Z,1744039323.855 [Supervisor](INFO): Main Thread ID is 5570 2025-04-07T15:22:03.855Z,1744039323.855 [Supervisor](DEBUG): Running supervisor. 2025-04-07T15:22:03.855Z,1744039323.855 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5662 2025-04-07T15:22:03.856Z,1744039323.856 [CommandExec](INFO): Initializing the command executive. 2025-04-07T15:22:03.857Z,1744039323.857 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5663 2025-04-07T15:22:03.860Z,1744039323.860 [controlThread ThreadHandler](INFO): Handler Thread ID is 5664 2025-04-07T15:22:03.860Z,1744039323.860 [controlThread](DEBUG): Initializing ControlThread 2025-04-07T15:22:03.861Z,1744039323.861 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-04-07T15:22:03.863Z,1744039323.863 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-04-07T15:22:03.864Z,1744039323.864 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-04-07T15:22:03.864Z,1744039323.864 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-04-07T15:22:03.866Z,1744039323.866 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-04-07T15:22:03.867Z,1744039323.867 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-04-07T15:22:03.873Z,1744039323.873 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-04-07T15:22:03.873Z,1744039323.873 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-04-07T15:22:03.873Z,1744039323.873 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-04-07T15:22:03.874Z,1744039323.874 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-04-07T15:22:03.874Z,1744039323.874 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-04-07T15:22:03.879Z,1744039323.879 [SBIT](INFO): Initialize SBIT Component. 2025-04-07T15:22:03.879Z,1744039323.879 [SBIT](IMPORTANT): git: 2025-03-10_A-1-g5ec0fbcf5 2025-04-07T15:22:03.880Z,1744039323.880 [SBIT](INFO): git hash: 5ec0fbcf5a93e8cd2bab2c372387092fa703e510 2025-04-07T15:22:03.880Z,1744039323.880 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-04-07T15:22:03.881Z,1744039323.881 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-04-07T15:22:03.882Z,1744039323.882 [SBIT](INFO): Beginning SBIT in 26.000000 seconds. 2025-04-07T15:22:03.883Z,1744039323.883 [logger ThreadHandler](INFO): Handler Thread ID is 5665 2025-04-07T15:22:03.899Z,1744039323.899 [IBIT](INFO): Initialize IBIT Component. 2025-04-07T15:22:03.900Z,1744039323.900 [CBIT](DEBUG): Initialize CBIT Component. 2025-04-07T15:22:03.907Z,1744039323.907 [DAT ThreadHandler](INFO): Handler Thread ID is 5666 2025-04-07T15:22:03.908Z,1744039323.908 [DAT](INFO): Powering up 2025-04-07T15:22:03.908Z,1744039323.908 [DAT](DEBUG): Initializing DAT. 2025-04-07T15:22:03.911Z,1744039323.911 [CBIT](DEBUG): Initialized mux pins. 2025-04-07T15:22:03.911Z,1744039323.911 [CBIT](DEBUG): Initializing the watchdog timer. 2025-04-07T15:22:03.912Z,1744039323.912 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5668 2025-04-07T15:22:03.914Z,1744039323.914 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-04-07T15:22:03.923Z,1744039323.923 [Onboard ThreadHandler](INFO): Handler Thread ID is 5669 2025-04-07T15:22:03.936Z,1744039323.936 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-04-07T15:22:03.936Z,1744039323.936 [CBIT](DEBUG): Initializing heartbeat. 2025-04-07T15:22:03.944Z,1744039323.944 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5670 2025-04-07T15:22:03.956Z,1744039323.956 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5671 2025-04-07T15:22:03.956Z,1744039323.956 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-04-07T15:22:03.960Z,1744039323.960 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5673 2025-04-07T15:22:03.967Z,1744039323.967 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5675 2025-04-07T15:22:03.969Z,1744039323.969 [WetLabsBB2FL](INFO): Powering up 2025-04-07T15:22:03.977Z,1744039323.977 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-04-07T15:22:03.977Z,1744039323.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-04-07T15:22:03.977Z,1744039323.977 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-04-07T15:22:03.977Z,1744039323.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-04-07T15:22:03.978Z,1744039323.978 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-04-07T15:22:03.978Z,1744039323.978 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-04-07T15:22:03.978Z,1744039323.978 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-04-07T15:22:03.978Z,1744039323.978 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-04-07T15:22:04.007Z,1744039324.007 [CBIT](DEBUG): Deactivating emergency mode. 2025-04-07T15:22:04.047Z,1744039324.047 [CBIT](DEBUG): Backplane powered. 2025-04-07T15:22:04.047Z,1744039324.047 [GFScanner](DEBUG): Initializing GFScanner 2025-04-07T15:22:04.047Z,1744039324.047 [GFScanner](DEBUG): Deactivating GF circuits. 2025-04-07T15:22:04.052Z,1744039324.052 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-04-07T15:22:04.052Z,1744039324.052 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-04-07T15:22:04.053Z,1744039324.053 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-04-07T15:22:04.054Z,1744039324.054 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-04-07T15:22:04.116Z,1744039324.116 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-04-07T15:22:04.120Z,1744039324.120 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-07T15:22:04.162Z,1744039324.162 [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-04-07T15:22:04.163Z,1744039324.163 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-04-07T15:22:04.163Z,1744039324.163 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-04-07T15:22:04.164Z,1744039324.164 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-04-07T15:22:04.363Z,1744039324.363 [Radio_Surface](INFO): Powering up 2025-04-07T15:22:04.500Z,1744039324.500 [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-04-07T15:22:04.509Z,1744039324.509 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-04-07T15:22:04.523Z,1744039324.523 [Default:A.Wait](DEBUG): Construct Wait. 2025-04-07T15:22:04.525Z,1744039324.525 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-07T15:22:04.561Z,1744039324.561 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-07T15:22:04.564Z,1744039324.564 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-07T15:22:04.578Z,1744039324.578 [Default:E.Execute](DEBUG): Construct Execute. 2025-04-07T15:22:04.581Z,1744039324.581 [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-04-07T15:22:04.603Z,1744039324.603 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,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,Reporter,LogSplitter, 2025-04-07T15:22:04.610Z,1744039324.610 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-04-07T15:22:04.694Z,1744039324.694 [Power24vConverter](INFO): Powering up. 2025-04-07T15:22:04.695Z,1744039324.695 [Sonardyne_Nano](INFO): Initializing. 2025-04-07T15:22:04.715Z,1744039324.715 [CANONSampler](INFO): Powering down 2025-04-07T15:22:04.752Z,1744039324.752 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-07T15:22:04.759Z,1744039324.759 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-07T15:22:04.760Z,1744039324.760 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-07T15:22:04.767Z,1744039324.767 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-07T15:22:04.768Z,1744039324.768 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-07T15:22:04.775Z,1744039324.775 [MassServo](DEBUG): Initializing MassServo. 2025-04-07T15:22:04.776Z,1744039324.776 [RudderServo](DEBUG): Initializing EZServoServo. 2025-04-07T15:22:04.783Z,1744039324.783 [RudderServo](DEBUG): Initializing RudderServo. 2025-04-07T15:22:04.784Z,1744039324.784 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-07T15:22:04.791Z,1744039324.791 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-07T15:22:06.095Z,1744039326.095 [WetLabsBB2FL](INFO): Powering down 2025-04-07T15:22:06.637Z,1744039326.637 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-04-07T15:22:08.279Z,1744039328.279 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2025-04-07T15:22:15.511Z,1744039335.511 [DAT](INFO): DAT read: 2025-04-07T15:22:15.513Z,1744039335.513 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-04-07T15:22:17.024Z,1744039337.024 [DAT](INFO): DAT read: MF Frequency Band 2025-04-07T15:22:17.025Z,1744039337.025 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-04-07T15:22:17.025Z,1744039337.025 [DAT](INFO): DAT read: Apr 3 2025 21:35:43 2025-04-07T15:22:17.845Z,1744039337.845 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004731 2025-04-07T15:22:18.284Z,1744039338.284 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-04-07T15:22:18.285Z,1744039338.285 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2025-04-07T15:22:18.285Z,1744039338.285 [DAT](INFO): commRate: 1200 2025-04-07T15:22:20.351Z,1744039340.351 [DAT](INFO): entering command mode 2025-04-07T15:22:20.551Z,1744039340.551 [DAT](INFO): DAT read: 2025-04-07T15:22:20.552Z,1744039340.552 [DAT](INFO): DAT read: user:1> 2025-04-07T15:22:20.552Z,1744039340.552 [DAT](INFO): setting verbose to 3 2025-04-07T15:22:20.803Z,1744039340.803 [DAT](INFO): DAT read: user:1> 2025-04-07T15:22:20.804Z,1744039340.804 [DAT](INFO): DAT read: Verbose | 3 2025-04-07T15:22:20.805Z,1744039340.805 [DAT](INFO): set verbose to 3 2025-04-07T15:22:20.805Z,1744039340.805 [DAT](INFO): setting DatVerbose to 27440 2025-04-07T15:22:21.056Z,1744039341.056 [DAT](INFO): DAT read: user:2> 2025-04-07T15:22:21.056Z,1744039341.056 [DAT](INFO): DAT read: DatVerbose | 27440 2025-04-07T15:22:21.057Z,1744039341.057 [DAT](INFO): set DatVerbose to 27440 2025-04-07T15:22:21.057Z,1744039341.057 [DAT](INFO): setting transmit power to 8 2025-04-07T15:22:21.308Z,1744039341.308 [DAT](INFO): DAT read: user:3> 2025-04-07T15:22:21.309Z,1744039341.309 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-04-07T15:22:21.309Z,1744039341.309 [DAT](INFO): set transmit power to 8 2025-04-07T15:22:21.309Z,1744039341.309 [DAT](INFO): setting local address to 8 2025-04-07T15:22:21.560Z,1744039341.560 [DAT](INFO): DAT read: user:4> 2025-04-07T15:22:21.560Z,1744039341.560 [DAT](INFO): DAT read: LocalAddr | 8 2025-04-07T15:22:21.561Z,1744039341.561 [DAT](INFO): set local address to 8 2025-04-07T15:22:21.562Z,1744039341.562 [DAT](INFO): Setting time to: 15:22:21 And date to:4/7/2025 2025-04-07T15:22:21.811Z,1744039341.811 [DAT](INFO): DAT read: user:5> 2025-04-07T15:22:21.812Z,1744039341.812 [DAT](INFO): DAT read: Mon Apr 7, 2025 15:22:21 2025-04-07T15:22:21.813Z,1744039341.813 [DAT](INFO): Local DAT time set to Mon Apr 7, 2025 15:22:21 2025-04-07T15:22:30.525Z,1744039350.525 [SBIT](IMPORTANT): Beginning Startup BIT 2025-04-07T15:22:30.554Z,1744039350.554 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-07T15:22:32.522Z,1744039352.522 [NAL9602](INFO): Powering up NAL9602 2025-04-07T15:22:35.035Z,1744039355.035 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.001479 CHAN A1 (24V): 0.000487 CHAN A2 (12V): -0.002483 CHAN A3 (5V): -0.001607 CHAN B0 (3.3V): -0.000493 CHAN B1 (3.15aV): -0.000096 CHAN B2 (3.15bV): 4.767929 CHAN B3 (GND): -0.000079 OPEN: -0.000194 Full Scale: +/- 1 mA 2025-04-07T15:22:43.416Z,1744039363.416 [NAL9602](INFO): NAL9602 initialized 2025-04-07T15:23:01.672Z,1744039381.672 [CommandExec](IMPORTANT): got command gfscan 2025-04-07T15:23:02.026Z,1744039382.026 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-07T15:23:06.488Z,1744039386.488 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.001855 CHAN A1 (24V): 0.000995 CHAN A2 (12V): -0.002216 CHAN A3 (5V): -0.001722 CHAN B0 (3.3V): -0.000048 CHAN B1 (3.15aV): -0.000095 CHAN B2 (3.15bV): 0.000101 CHAN B3 (GND): 0.000106 OPEN: -0.000520 Full Scale: +/- 1 mA 2025-04-07T15:23:10.482Z,1744039390.482 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:23:22.645Z,1744039402.645 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:23:24.314Z,1744039404.314 [SBIT](IMPORTANT): SBIT PASSED 2025-04-07T15:23:24.314Z,1744039404.314 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-04-07T15:23:24.315Z,1744039404.315 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count; 2025-04-07T15:23:24.315Z,1744039404.315 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2025-04-07T15:23:24.316Z,1744039404.316 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2025-04-07T15:23:24.316Z,1744039404.316 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool; 2025-04-07T15:23:24.316Z,1744039404.316 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool; 2025-04-07T15:23:24.316Z,1744039404.316 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=228.244267 cubic_centimeter; 2025-04-07T15:23:24.316Z,1744039404.316 [SBIT](IMPORTANT): VerticalControl.massDefault=1.588040 millimeter; 2025-04-07T15:23:24.705Z,1744039404.705 [MissionManager](IMPORTANT): Started mission Startup 2025-04-07T15:23:24.705Z,1744039404.705 [Startup] Running Loop=1 2025-04-07T15:23:24.705Z,1744039404.705 [Startup](DEBUG): Aggregate::initialize Startup 2025-04-07T15:23:24.705Z,1744039404.705 [Startup:A.GoToSurface] Running Loop=1 2025-04-07T15:23:24.705Z,1744039404.705 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-07T15:23:24.706Z,1744039404.706 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-07T15:23:24.707Z,1744039404.707 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-07T15:23:24.707Z,1744039404.707 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-07T15:23:24.707Z,1744039404.707 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-07T15:23:24.708Z,1744039404.708 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-07T15:23:24.708Z,1744039404.708 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-07T15:23:24.710Z,1744039404.710 [Startup:StartupSatComms] Running Loop=1 2025-04-07T15:23:24.710Z,1744039404.710 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-04-07T15:23:24.711Z,1744039404.711 [Startup:StartupSatComms:A] Running Loop=1 2025-04-07T15:23:25.110Z,1744039405.110 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-04-07T15:23:34.765Z,1744039414.765 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:23:46.481Z,1744039426.481 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:23:58.607Z,1744039438.607 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:24:10.317Z,1744039450.317 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:24:22.043Z,1744039462.043 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:24:24.872Z,1744039464.872 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-07T15:23:24.7Z 2025-04-07T15:24:24.872Z,1744039464.872 [Startup:StartupSatComms:A] Stopped 2025-04-07T15:24:24.872Z,1744039464.872 [Startup:StartupSatComms:B] Running Loop=1 2025-04-07T15:24:25.281Z,1744039465.281 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-07T15:24:31.963Z,1744039471.963 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250407T152151/Courier0000.lzma 2025-04-07T15:24:32.964Z,1744039472.964 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Courier0000.lzma.bak 2025-04-07T15:24:32.965Z,1744039472.965 [DataOverHttps](INFO): SBD MOMSN=24560166 2025-04-07T15:24:48.885Z,1744039488.885 [DataOverHttps](INFO): Sending 959 bytes from file Logs/20250407T152151/Express0001.lzma 2025-04-07T15:24:49.885Z,1744039489.885 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Express0001.lzma.bak 2025-04-07T15:24:49.885Z,1744039489.885 [DataOverHttps](INFO): SBD MOMSN=24560169 2025-04-07T15:24:51.192Z,1744039491.192 [Startup:StartupSatComms:B] Stopped 2025-04-07T15:24:51.192Z,1744039491.192 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-04-07T15:24:51.192Z,1744039491.192 [Startup:StartupSatComms] Stopped 2025-04-07T15:24:51.192Z,1744039491.192 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-04-07T15:24:51.193Z,1744039491.193 [Startup](INFO): Completed Startup 2025-04-07T15:24:51.193Z,1744039491.193 [MissionManager](INFO): Startup is completed. 2025-04-07T15:24:51.194Z,1744039491.194 [MissionManager](INFO): Uninitializing Mission Startup 2025-04-07T15:24:51.194Z,1744039491.194 [Startup] Stopped 2025-04-07T15:24:51.194Z,1744039491.194 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-04-07T15:24:51.194Z,1744039491.194 [Startup:A.GoToSurface] Stopped 2025-04-07T15:24:51.194Z,1744039491.194 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-07T15:24:51.596Z,1744039491.596 [MissionManager](IMPORTANT): Started mission Default 2025-04-07T15:24:51.596Z,1744039491.596 [Default] Running Loop=1 2025-04-07T15:24:51.596Z,1744039491.596 [Default](DEBUG): Aggregate::initialize Default 2025-04-07T15:24:51.596Z,1744039491.596 [Default:B.GoToSurface] Running Loop=1 2025-04-07T15:24:51.596Z,1744039491.596 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-07T15:24:51.597Z,1744039491.597 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-07T15:24:51.597Z,1744039491.597 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-07T15:24:51.597Z,1744039491.597 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-07T15:24:51.597Z,1744039491.597 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-07T15:24:51.598Z,1744039491.598 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-07T15:24:51.598Z,1744039491.598 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-07T15:24:51.598Z,1744039491.598 [Default:A.Wait] Running Loop=1 2025-04-07T15:24:51.598Z,1744039491.598 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-07T15:25:04.145Z,1744039504.145 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-04-07T15:25:04.145Z,1744039504.145 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:25:04.315Z,1744039504.315 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:25:04.667Z,1744039504.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:25:04.667Z,1744039504.667 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-04-07T15:25:04.932Z,1744039504.932 [Default:A.Wait](INFO): Done Waiting. 2025-04-07T15:25:04.932Z,1744039504.932 [Default:A.Wait] Stopped 2025-04-07T15:25:04.932Z,1744039504.932 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-07T15:25:05.342Z,1744039505.342 [Default:CheckIn] Running Loop=1 2025-04-07T15:25:05.342Z,1744039505.342 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-07T15:25:05.342Z,1744039505.342 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-07T15:25:05.737Z,1744039505.737 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-07T15:27:04.092Z,1744039624.092 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:27:17.426Z,1744039637.426 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:27:31.570Z,1744039651.570 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:27:35.811Z,1744039655.811 [CommandExec](IMPORTANT): got command failComponent 2025-04-07T15:27:35.811Z,1744039655.811 [CommandExec](IMPORTANT): Failed components: 2025-04-07T15:27:35.812Z,1744039655.812 [CommandExec](IMPORTANT): No failed Components. 2025-04-07T15:27:44.897Z,1744039664.897 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:27:47.329Z,1744039667.329 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-07T15:27:48.091Z,1744039668.091 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2025-04-07T15:27:48.092Z,1744039668.092 [CommandExec](IMPORTANT): Onboard.Pressure 9.397296 psi 2025-04-07T15:27:55.518Z,1744039675.518 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2025-04-07T15:27:55.518Z,1744039675.518 [CommandExec](IMPORTANT): Onboard.Humidity 17.299475 % 2025-04-07T15:27:58.229Z,1744039678.229 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:28:05.112Z,1744039685.112 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-04-07T15:28:05.112Z,1744039685.112 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:28:05.247Z,1744039685.247 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:28:05.663Z,1744039685.663 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:28:05.663Z,1744039685.663 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-04-07T15:28:11.563Z,1744039691.563 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:28:24.893Z,1744039704.893 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:28:38.225Z,1744039718.225 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:28:51.557Z,1744039731.557 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:29:04.903Z,1744039744.903 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:29:18.625Z,1744039758.625 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-07T15:29:25.097Z,1744039765.097 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-04-07T15:29:25.101Z,1744039765.101 [BPC1](INFO): Received data from all battery sticks. 2025-04-07T15:30:05.525Z,1744039805.525 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-07T15:25:05.3Z 2025-04-07T15:30:05.525Z,1744039805.525 [Default:CheckIn:Read_GPS] Stopped 2025-04-07T15:30:05.525Z,1744039805.525 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-07T15:30:05.933Z,1744039805.933 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-07T15:30:12.899Z,1744039812.899 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250407T152151/Courier0004.lzma 2025-04-07T15:30:13.901Z,1744039813.901 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Courier0004.lzma.bak 2025-04-07T15:30:13.901Z,1744039813.901 [DataOverHttps](INFO): SBD MOMSN=24560237 2025-04-07T15:30:29.747Z,1744039829.747 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20250407T152151/Express0005.lzma 2025-04-07T15:30:30.749Z,1744039830.749 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Express0005.lzma.bak 2025-04-07T15:30:30.749Z,1744039830.749 [DataOverHttps](INFO): SBD MOMSN=24560239 2025-04-07T15:30:31.766Z,1744039831.766 [Default:CheckIn:Read_Iridium] Stopped 2025-04-07T15:30:31.766Z,1744039831.766 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-07T15:30:31.766Z,1744039831.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-07T15:30:34.520Z,1744039834.520 [CommandExec](IMPORTANT): got command burn on 2025-04-07T15:30:34.520Z,1744039834.520 [CommandExec](IMPORTANT): Activating dropweight wire 2025-04-07T15:30:37.852Z,1744039837.852 [CommandExec](IMPORTANT): got command burn off 2025-04-07T15:30:37.852Z,1744039837.852 [CommandExec](IMPORTANT): Deactivating dropweight wire 2025-04-07T15:31:06.161Z,1744039866.161 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-04-07T15:31:06.161Z,1744039866.161 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:31:06.315Z,1744039866.315 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:31:06.715Z,1744039866.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:31:06.715Z,1744039866.715 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-04-07T15:32:45.504Z,1744039965.504 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-07T15:32:45.504Z,1744039965.504 [NAL9602] Data Fault, FailCount= 1 2025-04-07T15:32:45.504Z,1744039965.504 [NAL9602](ERROR): Data Fault 2025-04-07T15:32:45.652Z,1744039965.652 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-07T15:32:45.908Z,1744039965.908 [NAL9602](INFO): Powering down 2025-04-07T15:32:46.867Z,1744039966.867 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-07T15:32:46.867Z,1744039966.867 [NAL9602] No Fault, FailCount= 1 2025-04-07T15:33:16.208Z,1744039996.208 [NAL9602](INFO): Powering up NAL9602 2025-04-07T15:33:27.112Z,1744040007.112 [NAL9602](INFO): NAL9602 initialized 2025-04-07T15:34:07.136Z,1744040047.136 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-04-07T15:34:07.136Z,1744040047.136 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:34:07.300Z,1744040047.300 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:34:07.687Z,1744040047.687 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:34:07.687Z,1744040047.687 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-04-07T15:35:32.362Z,1744040132.362 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-07T15:35:32.362Z,1744040132.362 [Default:CheckIn:C.Wait] Stopped 2025-04-07T15:35:32.362Z,1744040132.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-07T15:35:32.362Z,1744040132.362 [Default:CheckIn:D] Running Loop=1 2025-04-07T15:35:32.778Z,1744040132.778 [Default:CheckIn:D] Stopped 2025-04-07T15:35:32.778Z,1744040132.778 [Default:CheckIn:E] Running Loop=1 2025-04-07T15:35:33.204Z,1744040133.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.686361 min 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn:E] Stopped 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn] Stopped 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn](INFO): Running loop #2 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn] Running Loop=2 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-07T15:35:33.205Z,1744040133.205 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-07T15:37:08.111Z,1744040228.111 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-04-07T15:37:08.111Z,1744040228.111 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:37:08.247Z,1744040228.247 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:37:08.663Z,1744040228.663 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:37:08.663Z,1744040228.663 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-04-07T15:40:09.099Z,1744040409.099 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-04-07T15:40:09.099Z,1744040409.099 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:40:09.110Z,1744040409.110 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:40:09.518Z,1744040409.518 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:40:09.519Z,1744040409.519 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-04-07T15:40:33.345Z,1744040433.345 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-07T15:35:33.2Z 2025-04-07T15:40:33.345Z,1744040433.345 [Default:CheckIn:Read_GPS] Stopped 2025-04-07T15:40:33.345Z,1744040433.345 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-07T15:40:40.982Z,1744040440.982 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250407T152151/Courier0007.lzma 2025-04-07T15:40:41.984Z,1744040441.984 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Courier0007.lzma.bak 2025-04-07T15:40:41.985Z,1744040441.985 [DataOverHttps](INFO): SBD MOMSN=24560313 2025-04-07T15:41:00.511Z,1744040460.511 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20250407T152151/Express0008.lzma 2025-04-07T15:41:01.512Z,1744040461.512 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Express0008.lzma.bak 2025-04-07T15:41:01.513Z,1744040461.513 [DataOverHttps](INFO): SBD MOMSN=24560315 2025-04-07T15:41:02.889Z,1744040462.889 [Default:CheckIn:Read_Iridium] Stopped 2025-04-07T15:41:02.889Z,1744040462.889 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-07T15:41:02.889Z,1744040462.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-07T15:42:10.816Z,1744040530.816 [CommandExec](IMPORTANT): got command failComponent 2025-04-07T15:42:10.816Z,1744040530.816 [CommandExec](IMPORTANT): Failed components: 2025-04-07T15:42:10.816Z,1744040530.816 [CommandExec](IMPORTANT): No failed Components. 2025-04-07T15:42:14.996Z,1744040534.996 [CommandExec](IMPORTANT): got command strobe off 2025-04-07T15:42:14.996Z,1744040534.996 [CommandExec](IMPORTANT): Deactivating strobe 2025-04-07T15:43:10.155Z,1744040590.155 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-04-07T15:43:10.155Z,1744040590.155 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:43:10.166Z,1744040590.166 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:43:10.569Z,1744040590.569 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:43:10.569Z,1744040590.569 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-04-07T15:43:29.112Z,1744040609.112 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-07T15:43:29.112Z,1744040609.112 [NAL9602] Data Fault, FailCount= 2 2025-04-07T15:43:29.112Z,1744040609.112 [NAL9602](ERROR): Data Fault 2025-04-07T15:43:29.130Z,1744040609.130 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-07T15:43:29.508Z,1744040609.508 [NAL9602](INFO): Powering down 2025-04-07T15:43:30.337Z,1744040610.337 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-07T15:43:30.338Z,1744040610.338 [NAL9602] No Fault, FailCount= 2 2025-04-07T15:43:59.816Z,1744040639.816 [NAL9602](INFO): Powering up NAL9602 2025-04-07T15:44:10.740Z,1744040650.740 [NAL9602](INFO): NAL9602 initialized 2025-04-07T15:45:31.860Z,1744040731.860 [CommandExec](IMPORTANT): got command failComponent 2025-04-07T15:45:31.860Z,1744040731.860 [CommandExec](IMPORTANT): Failed components: 2025-04-07T15:45:31.860Z,1744040731.860 [CommandExec](IMPORTANT): No failed Components. 2025-04-07T15:46:03.493Z,1744040763.493 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-07T15:46:03.493Z,1744040763.493 [Default:CheckIn:C.Wait] Stopped 2025-04-07T15:46:03.493Z,1744040763.493 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-07T15:46:03.493Z,1744040763.493 [Default:CheckIn:D] Running Loop=1 2025-04-07T15:46:03.885Z,1744040763.885 [Default:CheckIn:D] Stopped 2025-04-07T15:46:03.885Z,1744040763.885 [Default:CheckIn:E] Running Loop=1 2025-04-07T15:46:04.294Z,1744040764.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.204818 min 2025-04-07T15:46:04.295Z,1744040764.295 [Default:CheckIn:E] Stopped 2025-04-07T15:46:04.295Z,1744040764.295 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-07T15:46:04.295Z,1744040764.295 [Default:CheckIn] Stopped 2025-04-07T15:46:04.295Z,1744040764.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-07T15:46:04.296Z,1744040764.296 [Default:CheckIn](INFO): Running loop #3 2025-04-07T15:46:04.296Z,1744040764.296 [Default:CheckIn] Running Loop=3 2025-04-07T15:46:04.296Z,1744040764.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-07T15:46:04.296Z,1744040764.296 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-07T15:46:11.175Z,1744040771.175 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-04-07T15:46:11.175Z,1744040771.175 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:46:11.194Z,1744040771.194 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:46:11.602Z,1744040771.602 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:46:11.607Z,1744040771.607 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2025-04-07T15:49:12.163Z,1744040952.163 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2025-04-07T15:49:12.163Z,1744040952.163 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-07T15:49:12.207Z,1744040952.207 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-07T15:49:12.589Z,1744040952.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-07T15:49:12.589Z,1744040952.589 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2025-04-07T15:51:04.054Z,1744041064.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155102.00,A,3648.16498,N,12147.27699,W,0.544,238.09,070425,,,A*76 2025-04-07T15:51:04.057Z,1744041064.057 [NAL9602](INFO): GPS fix at 20250407T155102: (36.802750, -121.787950) 2025-04-07T15:51:04.069Z,1744041064.069 [Default:CheckIn:Read_GPS] Stopped 2025-04-07T15:51:04.069Z,1744041064.069 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-07T15:51:14.259Z,1744041074.259 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250407T152151/Courier0010.lzma 2025-04-07T15:51:16.264Z,1744041076.264 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Courier0010.lzma.bak 2025-04-07T15:51:16.265Z,1744041076.265 [DataOverHttps](INFO): SBD MOMSN=24560338 2025-04-07T15:51:35.655Z,1744041095.655 [DataOverHttps](INFO): Sending 467 bytes from file Logs/20250407T152151/Express0011.lzma 2025-04-07T15:51:36.395Z,1744041096.395 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-07T15:51:36.656Z,1744041096.656 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Express0011.lzma.bak 2025-04-07T15:51:36.657Z,1744041096.657 [DataOverHttps](INFO): SBD MOMSN=24560378 2025-04-07T15:51:38.030Z,1744041098.030 [Default:CheckIn:Read_Iridium] Stopped 2025-04-07T15:51:38.030Z,1744041098.030 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-07T15:51:38.030Z,1744041098.030 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-07T15:54:18.332Z,1744041258.332 [DataOverHttps](IMPORTANT): SBD MTMSN=20250407T155417 2025-04-07T15:54:28.683Z,1744041268.683 [DataOverHttps](INFO): Received command: ibit 2025-04-07T15:54:28.767Z,1744041268.767 [CommandExec](IMPORTANT): got command ibit 2025-04-07T15:54:28.985Z,1744041268.985 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-04-07T15:54:28.985Z,1744041268.985 [IBIT](IMPORTANT): Beginning control surface checks. 2025-04-07T15:54:28.989Z,1744041268.989 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-07T15:54:30.583Z,1744041270.583 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155428.00,A,3648.14135,N,12147.24297,W,3.538,111.10,070425,,,D*7F 2025-04-07T15:54:30.586Z,1744041270.586 [NAL9602](INFO): GPS fix at 20250407T155428: (36.802356, -121.787383) 2025-04-07T15:54:33.591Z,1744041273.591 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002433 CHAN A1 (24V): 0.000171 CHAN A2 (12V): -0.002347 CHAN A3 (5V): -0.001300 CHAN B0 (3.3V): 0.000097 CHAN B1 (3.15aV): -0.000472 CHAN B2 (3.15bV): -0.000282 CHAN B3 (GND): 0.000214 OPEN: -0.000200 Full Scale: +/- 1 mA 2025-04-07T15:54:58.117Z,1744041298.117 [NAL9602](INFO): SBD MO Status=2, MOMSN=62731, MT Status=2, MTMSN=0 2025-04-07T15:54:58.117Z,1744041298.117 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-07T15:55:13.073Z,1744041313.073 [NAL9602](INFO): SBD MO Status=2, MOMSN=62731, MT Status=2, MTMSN=0 2025-04-07T15:55:13.074Z,1744041313.074 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-07T15:55:14.845Z,1744041314.845 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802357 Longitude: -121.787384 2025-04-07T15:55:15.232Z,1744041315.232 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.650999 2025-04-07T15:55:15.232Z,1744041315.232 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2025-04-07T15:55:15.233Z,1744041315.233 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2025-04-07T15:55:15.666Z,1744041315.666 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-04-07T15:55:15.666Z,1744041315.666 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-04-07T15:55:15.667Z,1744041315.667 [IBIT](IMPORTANT): Pressure:9.406138 PSI 2025-04-07T15:55:15.667Z,1744041315.667 [IBIT](IMPORTANT): Humidity:16.994263 % 2025-04-07T15:55:16.072Z,1744041316.072 [IBIT](IMPORTANT): Vehicle Pitch:-1.712684 degrees 2025-04-07T15:55:16.072Z,1744041316.072 [IBIT](IMPORTANT): Vehicle Roll:5.792597 degrees 2025-04-07T15:55:16.073Z,1744041316.073 [IBIT](IMPORTANT): Vehicle Heading:16.736959 degrees 2025-04-07T15:55:16.460Z,1744041316.460 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-04-07T15:55:16.460Z,1744041316.460 [IBIT](IMPORTANT): buoyancyNeutral: 228.244263 cc 2025-04-07T15:55:16.461Z,1744041316.461 [IBIT](IMPORTANT): massDefault: 0.158804 cm 2025-04-07T15:55:16.461Z,1744041316.461 [IBIT](IMPORTANT): stopDepth: 250.000000 m 2025-04-07T15:55:16.461Z,1744041316.461 [IBIT](IMPORTANT): abortDepth: 270.000000 m 2025-04-07T15:55:16.461Z,1744041316.461 [IBIT](IMPORTANT): IBIT PASSED 2025-04-07T15:55:41.345Z,1744041341.345 [NAL9602](INFO): SBD MO Status=0, MOMSN=62731, MT Status=0, MTMSN=0 2025-04-07T15:55:41.345Z,1744041341.345 [NAL9602](INFO): No messages in MT queue 2025-04-07T15:55:59.020Z,1744041359.020 [DataOverHttps](IMPORTANT): SBD MTMSN=20250407T155558 2025-04-07T15:56:09.387Z,1744041369.387 [DataOverHttps](INFO): Received command: failc 2025-04-07T15:56:09.415Z,1744041369.415 [CommandExec](IMPORTANT): got command failComponent 2025-04-07T15:56:09.416Z,1744041369.416 [CommandExec](IMPORTANT): Failed components: 2025-04-07T15:56:09.416Z,1744041369.416 [CommandExec](IMPORTANT): No failed Components. 2025-04-07T15:56:12.119Z,1744041372.119 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-07T15:56:38.507Z,1744041398.507 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-07T15:56:38.507Z,1744041398.507 [Default:CheckIn:C.Wait] Stopped 2025-04-07T15:56:38.507Z,1744041398.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-07T15:56:38.507Z,1744041398.507 [Default:CheckIn:D] Running Loop=1 2025-04-07T15:56:38.891Z,1744041398.891 [Default:CheckIn:D] Stopped 2025-04-07T15:56:38.891Z,1744041398.891 [Default:CheckIn:E] Running Loop=1 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.788255 min 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn:E] Stopped 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn] Stopped 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn](INFO): Running loop #4 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn] Running Loop=4 2025-04-07T15:56:39.343Z,1744041399.343 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-07T15:56:39.344Z,1744041399.344 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-07T15:56:41.285Z,1744041401.285 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155640.00,A,3648.14765,N,12147.21946,W,0.039,55.11,070425,,,D*45 2025-04-07T15:56:41.288Z,1744041401.288 [NAL9602](INFO): GPS fix at 20250407T155640: (36.802461, -121.786991) 2025-04-07T15:56:41.339Z,1744041401.339 [Default:CheckIn:Read_GPS] Stopped 2025-04-07T15:56:41.340Z,1744041401.340 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-07T15:56:43.235Z,1744041403.235 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250407T152151/Courier0013.lzma 2025-04-07T15:56:43.497Z,1744041403.497 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Courier0013.lzma.bak 2025-04-07T15:56:43.497Z,1744041403.497 [DataOverHttps](INFO): SBD MOMSN=24560401 2025-04-07T15:56:51.633Z,1744041411.633 [NAL9602](INFO): SBD MO Status=0, MOMSN=62732, MT Status=0, MTMSN=0 2025-04-07T15:56:51.633Z,1744041411.633 [NAL9602](INFO): No messages in MT queue 2025-04-07T15:57:12.517Z,1744041432.517 [DataOverHttps](INFO): Sending 928 bytes from file Logs/20250407T152151/Express0014.lzma 2025-04-07T15:57:13.516Z,1744041433.516 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Express0014.lzma.bak 2025-04-07T15:57:13.517Z,1744041433.517 [DataOverHttps](INFO): SBD MOMSN=24560405 2025-04-07T15:57:14.782Z,1744041434.782 [Default:CheckIn:Read_Iridium] Stopped 2025-04-07T15:57:14.782Z,1744041434.782 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-07T15:57:14.783Z,1744041434.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-07T15:57:22.049Z,1744041442.049 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-07T16:02:15.578Z,1744041735.578 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-07T16:02:15.578Z,1744041735.578 [Default:CheckIn:C.Wait] Stopped 2025-04-07T16:02:15.578Z,1744041735.578 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-07T16:02:15.578Z,1744041735.578 [Default:CheckIn:D] Running Loop=1 2025-04-07T16:02:15.991Z,1744041735.991 [Default:CheckIn:D] Stopped 2025-04-07T16:02:15.992Z,1744041735.992 [Default:CheckIn:E] Running Loop=1 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.406563 min 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn:E] Stopped 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn] Stopped 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn](INFO): Running loop #5 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn] Running Loop=5 2025-04-07T16:02:16.401Z,1744041736.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-07T16:02:16.402Z,1744041736.402 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-07T16:02:18.392Z,1744041738.392 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160217.00,A,3648.14656,N,12147.21279,W,0.078,55.11,070425,,,D*46 2025-04-07T16:02:18.403Z,1744041738.403 [NAL9602](INFO): GPS fix at 20250407T160217: (36.802443, -121.786880) 2025-04-07T16:02:18.415Z,1744041738.415 [Default:CheckIn:Read_GPS] Stopped 2025-04-07T16:02:18.415Z,1744041738.415 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-07T16:02:28.790Z,1744041748.790 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250407T152151/Courier0018.lzma 2025-04-07T16:02:29.792Z,1744041749.792 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Courier0018.lzma.bak 2025-04-07T16:02:29.793Z,1744041749.793 [DataOverHttps](INFO): SBD MOMSN=24560449 2025-04-07T16:02:35.619Z,1744041755.619 [NAL9602](INFO): SBD MO Status=0, MOMSN=62733, MT Status=0, MTMSN=0 2025-04-07T16:02:35.619Z,1744041755.619 [NAL9602](INFO): No messages in MT queue 2025-04-07T16:02:45.990Z,1744041765.990 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250407T152151/Express0020.lzma 2025-04-07T16:02:46.993Z,1744041766.993 [DataOverHttps](INFO): Moved sent file to Logs/20250407T152151/Express0020.lzma.bak 2025-04-07T16:02:46.993Z,1744041766.993 [DataOverHttps](INFO): SBD MOMSN=24560482 2025-04-07T16:02:48.155Z,1744041768.155 [Default:CheckIn:Read_Iridium] Stopped 2025-04-07T16:02:48.155Z,1744041768.155 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-07T16:02:48.155Z,1744041768.155 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-07T16:03:06.385Z,1744041786.385 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-07T16:03:26.708Z,1744041806.708 [DataOverHttps](IMPORTANT): SBD MTMSN=20250407T160325 2025-04-07T16:03:34.438Z,1744041814.438 [DataOverHttps](INFO): Received command: restart app 2025-04-07T16:03:34.528Z,1744041814.528 [CommandExec](IMPORTANT): got command restart application 2025-04-07T16:03:35.531Z,1744041815.531 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-04-07T16:03:35.531Z,1744041815.531 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:35.531Z,1744041815.531 [CommandExec](INFO): Uninitializing the command executive. 2025-04-07T16:03:35.531Z,1744041815.531 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-07T16:03:35.532Z,1744041815.532 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:35.679Z,1744041815.679 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-07T16:03:35.679Z,1744041815.679 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-07T16:03:35.679Z,1744041815.679 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:35.680Z,1744041815.680 [NavChartDb](INFO): Join timeout helper Thread ID is 5868 2025-04-07T16:03:35.763Z,1744041815.763 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:35.763Z,1744041815.763 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:35.767Z,1744041815.767 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-07T16:03:35.767Z,1744041815.767 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:35.767Z,1744041815.767 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5869 2025-04-07T16:03:36.104Z,1744041816.104 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:36.104Z,1744041816.104 [WetLabsBB2FL](INFO): Powering down 2025-04-07T16:03:36.105Z,1744041816.105 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:36.115Z,1744041816.115 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-07T16:03:36.115Z,1744041816.115 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:36.115Z,1744041816.115 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5870 2025-04-07T16:03:36.567Z,1744041816.567 [CTD_Seabird](INFO): Powering down 2025-04-07T16:03:36.579Z,1744041816.579 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:36.579Z,1744041816.579 [CTD_Seabird](INFO): Powering down 2025-04-07T16:03:36.591Z,1744041816.591 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:36.599Z,1744041816.599 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-07T16:03:36.599Z,1744041816.599 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:36.599Z,1744041816.599 [Radio_Surface](INFO): Join timeout helper Thread ID is 5871 2025-04-07T16:03:36.887Z,1744041816.887 [Radio_Surface](INFO): Powering down 2025-04-07T16:03:36.888Z,1744041816.888 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:36.888Z,1744041816.888 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:36.903Z,1744041816.903 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-07T16:03:36.903Z,1744041816.903 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:36.903Z,1744041816.903 [Onboard](INFO): Join timeout helper Thread ID is 5872 2025-04-07T16:03:37.447Z,1744041817.447 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-07T16:03:41.107Z,1744041821.107 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:41.107Z,1744041821.107 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.112Z,1744041821.112 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-07T16:03:41.112Z,1744041821.112 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.112Z,1744041821.112 [DataOverHttps](INFO): Join timeout helper Thread ID is 5873 2025-04-07T16:03:41.463Z,1744041821.463 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:41.463Z,1744041821.463 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.475Z,1744041821.475 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-04-07T16:03:41.475Z,1744041821.475 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.476Z,1744041821.476 [DAT](INFO): Join timeout helper Thread ID is 5874 2025-04-07T16:03:41.651Z,1744041821.651 [DAT](INFO): Powering down 2025-04-07T16:03:41.723Z,1744041821.723 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:41.723Z,1744041821.723 [DAT](INFO): Powering down 2025-04-07T16:03:41.724Z,1744041821.724 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.747Z,1744041821.747 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-07T16:03:41.748Z,1744041821.748 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.748Z,1744041821.748 [logger](INFO): Join timeout helper Thread ID is 5875 2025-04-07T16:03:41.774Z,1744041821.774 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:41.774Z,1744041821.774 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.775Z,1744041821.775 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-07T16:03:41.776Z,1744041821.776 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.776Z,1744041821.776 [CommandLine](INFO): Join timeout helper Thread ID is 5876 2025-04-07T16:03:41.803Z,1744041821.803 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:41.803Z,1744041821.803 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.815Z,1744041821.815 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-07T16:03:41.815Z,1744041821.815 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.816Z,1744041821.816 [CommandExec](INFO): Join timeout helper Thread ID is 5877 2025-04-07T16:03:41.817Z,1744041821.817 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-07T16:03:41.817Z,1744041821.817 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:41.818Z,1744041821.818 [controlThread](INFO): Join timeout helper Thread ID is 5878 2025-04-07T16:03:41.919Z,1744041821.919 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-07T16:03:41.919Z,1744041821.919 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-07T16:03:41.919Z,1744041821.919 [AHRS_M2](INFO): Powering down 2025-04-07T16:03:41.991Z,1744041821.991 [NAL9602](INFO): Powering down 2025-04-07T16:03:41.993Z,1744041821.993 [Sonardyne_Nano](INFO): Powering down 2025-04-07T16:03:42.112Z,1744041822.112 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-07T16:03:42.113Z,1744041822.113 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-07T16:03:42.113Z,1744041822.113 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-07T16:03:42.114Z,1744041822.114 [MissionManager](INFO): Uninitializing Mission Default 2025-04-07T16:03:42.114Z,1744041822.114 [Default] Stopped 2025-04-07T16:03:42.114Z,1744041822.114 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-07T16:03:42.114Z,1744041822.114 [Default:B.GoToSurface] Stopped 2025-04-07T16:03:42.114Z,1744041822.114 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-07T16:03:42.114Z,1744041822.114 [Default:CheckIn] Stopped 2025-04-07T16:03:42.115Z,1744041822.115 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-07T16:03:42.115Z,1744041822.115 [Default:CheckIn:C.Wait] Stopped 2025-04-07T16:03:42.115Z,1744041822.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-07T16:03:42.118Z,1744041822.118 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-07T16:03:42.118Z,1744041822.118 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-07T16:03:42.118Z,1744041822.118 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-07T16:03:42.119Z,1744041822.119 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-07T16:03:42.119Z,1744041822.119 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-07T16:03:42.119Z,1744041822.119 [BuoyancyServo](INFO): Powering down 2025-04-07T16:03:42.131Z,1744041822.131 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-07T16:03:42.131Z,1744041822.131 [ElevatorServo](INFO): Powering down 2025-04-07T16:03:42.132Z,1744041822.132 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-07T16:03:42.132Z,1744041822.132 [MassServo](INFO): Powering down 2025-04-07T16:03:42.133Z,1744041822.133 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-07T16:03:42.133Z,1744041822.133 [RudderServo](INFO): Powering down 2025-04-07T16:03:42.134Z,1744041822.134 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-07T16:03:42.134Z,1744041822.134 [ThrusterHE](INFO): Powering down 2025-04-07T16:03:42.135Z,1744041822.135 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-07T16:03:42.135Z,1744041822.135 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-07T16:03:42.135Z,1744041822.135 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-07T16:03:42.135Z,1744041822.135 [CBIT](DEBUG): Powering off loads. 2025-04-07T16:03:42.146Z,1744041822.146 [CBIT](DEBUG): Disabling WDT. 2025-04-07T16:03:42.159Z,1744041822.159 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-07T16:03:42.159Z,1744041822.159 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-07T16:03:42.160Z,1744041822.160 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.345Z,1744041822.345 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.348Z,1744041822.348 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.354Z,1744041822.354 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.361Z,1744041822.361 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.443Z,1744041822.443 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.447Z,1744041822.447 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.477Z,1744041822.477 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-07T16:03:42.477Z,1744041822.477 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-07T16:03:42.501Z,1744041822.501 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-07T16:03:42.582Z,1744041822.582 [logger ThreadHandler](INFO): Thread cancelled.