2025-03-10T18:34:56.806Z,1741631696.806 [Supervisor](DEBUG): Initializing supervisor. 2025-03-10T18:34:56.811Z,1741631696.811 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-10T18:34:56.812Z,1741631696.812 [SyncHandler](INFO): Protected caller Thread ID is 3993 2025-03-10T18:34:56.813Z,1741631696.813 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-10T18:34:56.814Z,1741631696.814 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-10T18:34:56.814Z,1741631696.814 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3994 2025-03-10T18:34:56.818Z,1741631696.818 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-10T18:34:56.839Z,1741631696.839 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-10T18:34:56.840Z,1741631696.840 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-10T18:34:56.840Z,1741631696.840 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3995 2025-03-10T18:34:56.844Z,1741631696.844 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-10T18:34:56.845Z,1741631696.845 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-10T18:34:56.846Z,1741631696.846 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3996 2025-03-10T18:34:56.848Z,1741631696.848 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-10T18:34:56.849Z,1741631696.849 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-10T18:34:56.849Z,1741631696.849 [logger ThreadHandler](INFO): Protected caller Thread ID is 3997 2025-03-10T18:34:56.853Z,1741631696.853 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-10T18:34:56.854Z,1741631696.854 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-10T18:34:56.856Z,1741631696.856 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-10T18:34:57.147Z,1741631697.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-10T18:34:57.148Z,1741631697.148 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-10T18:34:57.242Z,1741631697.242 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-10T18:34:57.810Z,1741631697.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-10T18:34:57.810Z,1741631697.810 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-10T18:34:58.187Z,1741631698.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-10T18:34:58.188Z,1741631698.188 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-10T18:34:58.298Z,1741631698.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-10T18:34:58.299Z,1741631698.299 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-10T18:34:58.834Z,1741631698.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-10T18:34:58.835Z,1741631698.835 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-10T18:34:59.225Z,1741631699.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-10T18:34:59.226Z,1741631699.226 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-10T18:34:59.791Z,1741631699.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-10T18:34:59.792Z,1741631699.792 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-10T18:34:59.944Z,1741631699.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-10T18:34:59.944Z,1741631699.944 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-10T18:35:00.037Z,1741631700.037 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-10T18:35:00.936Z,1741631700.936 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-10T18:35:00.936Z,1741631700.936 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-10T18:35:01.344Z,1741631701.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-10T18:35:01.344Z,1741631701.344 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-10T18:35:01.565Z,1741631701.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-10T18:35:01.566Z,1741631701.566 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-10T18:35:01.702Z,1741631701.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-10T18:35:01.703Z,1741631701.703 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-10T18:35:01.958Z,1741631701.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-10T18:35:01.959Z,1741631701.959 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-10T18:35:02.234Z,1741631702.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-10T18:35:02.236Z,1741631702.236 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2025-03-10T18:35:02.237Z,1741631702.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2025-03-10T18:35:02.337Z,1741631702.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2025-03-10T18:35:02.454Z,1741631702.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2025-03-10T18:35:02.552Z,1741631702.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2025-03-10T18:35:02.670Z,1741631702.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2025-03-10T18:35:02.780Z,1741631702.780 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2025-03-10T18:35:02.914Z,1741631702.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2025-03-10T18:35:03.089Z,1741631703.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2025-03-10T18:35:03.259Z,1741631703.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2025-03-10T18:35:03.386Z,1741631703.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2025-03-10T18:35:03.667Z,1741631703.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-10T18:35:03.668Z,1741631703.668 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2025-03-10T18:35:03.668Z,1741631703.668 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-03-10T18:35:03.677Z,1741631703.677 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-10T18:35:03.732Z,1741631703.732 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-10T18:35:03.834Z,1741631703.834 [VerticalControl] Loaded 2025-03-10T18:35:03.835Z,1741631703.835 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-10T18:35:03.852Z,1741631703.852 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-10T18:35:04.152Z,1741631704.152 [HorizontalControl] Loaded 2025-03-10T18:35:04.152Z,1741631704.152 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-10T18:35:04.155Z,1741631704.155 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-10T18:35:04.221Z,1741631704.221 [SpeedControl] Loaded 2025-03-10T18:35:04.221Z,1741631704.221 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-10T18:35:04.224Z,1741631704.224 [LoopControl](DEBUG): Construct LoopControl. 2025-03-10T18:35:04.224Z,1741631704.224 [LoopControl] Loaded 2025-03-10T18:35:04.224Z,1741631704.224 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-10T18:35:04.225Z,1741631704.225 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-10T18:35:04.225Z,1741631704.225 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-10T18:35:04.259Z,1741631704.259 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-10T18:35:04.294Z,1741631704.294 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-10T18:35:04.572Z,1741631704.572 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-10T18:35:04.572Z,1741631704.572 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-10T18:35:04.736Z,1741631704.736 [BuoyancyServo] Loaded 2025-03-10T18:35:04.736Z,1741631704.736 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-10T18:35:04.759Z,1741631704.759 [ElevatorServo] Loaded 2025-03-10T18:35:04.759Z,1741631704.759 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-10T18:35:04.781Z,1741631704.781 [MassServo] Loaded 2025-03-10T18:35:04.781Z,1741631704.781 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-10T18:35:04.802Z,1741631704.802 [RudderServo] Loaded 2025-03-10T18:35:04.802Z,1741631704.802 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-10T18:35:04.820Z,1741631704.820 [ThrusterHE] Loaded 2025-03-10T18:35:04.820Z,1741631704.820 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-10T18:35:04.820Z,1741631704.820 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-10T18:35:04.821Z,1741631704.821 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-10T18:35:04.936Z,1741631704.936 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-10T18:35:04.936Z,1741631704.936 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-10T18:35:04.950Z,1741631704.950 [NavChart] Loaded 2025-03-10T18:35:04.950Z,1741631704.950 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-10T18:35:04.956Z,1741631704.956 [UniversalFixResidualReporter] Loaded 2025-03-10T18:35:04.956Z,1741631704.956 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-10T18:35:04.957Z,1741631704.957 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-10T18:35:04.957Z,1741631704.957 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-10T18:35:05.017Z,1741631705.017 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-10T18:35:05.018Z,1741631705.018 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-10T18:35:05.495Z,1741631705.495 [AHRS_M2] Loaded 2025-03-10T18:35:05.496Z,1741631705.496 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-10T18:35:06.307Z,1741631706.307 [BPC1] Loaded 2025-03-10T18:35:06.307Z,1741631706.307 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-10T18:35:06.408Z,1741631706.408 [DAT] Loaded 2025-03-10T18:35:06.408Z,1741631706.408 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-03-10T18:35:06.410Z,1741631706.410 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094B4E0 2025-03-10T18:35:06.410Z,1741631706.410 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4076 2025-03-10T18:35:06.483Z,1741631706.483 [DataOverHttps] Loaded 2025-03-10T18:35:06.484Z,1741631706.484 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-10T18:35:06.485Z,1741631706.485 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097B4E0 2025-03-10T18:35:06.485Z,1741631706.485 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4077 2025-03-10T18:35:06.508Z,1741631706.508 [Depth_Keller] Loaded 2025-03-10T18:35:06.509Z,1741631706.509 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-10T18:35:06.514Z,1741631706.514 [DropWeight] Loaded 2025-03-10T18:35:06.514Z,1741631706.514 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-10T18:35:06.579Z,1741631706.579 [NAL9602] Loaded 2025-03-10T18:35:06.580Z,1741631706.580 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-10T18:35:06.610Z,1741631706.610 [Onboard] Loaded 2025-03-10T18:35:06.610Z,1741631706.610 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-10T18:35:06.611Z,1741631706.611 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0 2025-03-10T18:35:06.612Z,1741631706.612 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4078 2025-03-10T18:35:06.626Z,1741631706.626 [Power24vConverter] Loaded 2025-03-10T18:35:06.626Z,1741631706.626 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-10T18:35:06.643Z,1741631706.643 [Radio_Surface] Loaded 2025-03-10T18:35:06.643Z,1741631706.643 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-10T18:35:06.644Z,1741631706.644 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0 2025-03-10T18:35:06.645Z,1741631706.645 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4079 2025-03-10T18:35:06.660Z,1741631706.660 [Sonardyne_Nano] Loaded 2025-03-10T18:35:06.661Z,1741631706.661 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-03-10T18:35:06.661Z,1741631706.661 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-10T18:35:06.662Z,1741631706.662 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-10T18:35:06.714Z,1741631706.714 [DepthRateCalculator] Loaded 2025-03-10T18:35:06.714Z,1741631706.714 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-10T18:35:06.719Z,1741631706.719 [PitchRateCalculator] Loaded 2025-03-10T18:35:06.720Z,1741631706.720 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-10T18:35:06.729Z,1741631706.729 [SpeedCalculator] Loaded 2025-03-10T18:35:06.729Z,1741631706.729 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-10T18:35:06.733Z,1741631706.733 [YawRateCalculator] Loaded 2025-03-10T18:35:06.734Z,1741631706.734 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-10T18:35:06.753Z,1741631706.753 [ElevatorOffsetCalculator] Loaded 2025-03-10T18:35:06.753Z,1741631706.753 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-10T18:35:06.754Z,1741631706.754 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-10T18:35:06.754Z,1741631706.754 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-10T18:35:06.886Z,1741631706.886 [CANONSampler] Loaded 2025-03-10T18:35:06.886Z,1741631706.886 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2025-03-10T18:35:06.952Z,1741631706.952 [CTD_Seabird] Loaded 2025-03-10T18:35:06.952Z,1741631706.952 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-10T18:35:06.953Z,1741631706.953 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A964E0 2025-03-10T18:35:06.953Z,1741631706.953 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4080 2025-03-10T18:35:06.976Z,1741631706.976 [PAR_Licor] Loaded 2025-03-10T18:35:06.977Z,1741631706.977 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-10T18:35:07.025Z,1741631707.025 [WetLabsBB2FL] Loaded 2025-03-10T18:35:07.025Z,1741631707.025 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-03-10T18:35:07.026Z,1741631707.026 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC64E0 2025-03-10T18:35:07.026Z,1741631707.026 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4081 2025-03-10T18:35:07.027Z,1741631707.027 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-10T18:35:07.028Z,1741631707.028 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-10T18:35:07.057Z,1741631707.057 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-10T18:35:07.058Z,1741631707.058 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-10T18:35:07.405Z,1741631707.405 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-10T18:35:07.406Z,1741631707.406 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-10T18:35:07.568Z,1741631707.568 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-10T18:35:07.577Z,1741631707.577 [SBIT] Loaded 2025-03-10T18:35:07.577Z,1741631707.577 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-10T18:35:07.580Z,1741631707.580 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-10T18:35:07.593Z,1741631707.593 [IBIT] Loaded 2025-03-10T18:35:07.594Z,1741631707.594 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-10T18:35:07.599Z,1741631707.599 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-10T18:35:07.684Z,1741631707.684 [CBIT] Loaded 2025-03-10T18:35:07.684Z,1741631707.684 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-10T18:35:07.717Z,1741631707.717 [GFScanner] Loaded 2025-03-10T18:35:07.717Z,1741631707.717 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-10T18:35:07.718Z,1741631707.718 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-10T18:35:07.718Z,1741631707.718 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-10T18:35:07.852Z,1741631707.852 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-10T18:35:07.859Z,1741631707.859 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-10T18:35:07.862Z,1741631707.862 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-10T18:35:07.873Z,1741631707.873 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-10T18:35:07.874Z,1741631707.874 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-03-10T18:35:07.874Z,1741631707.874 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4082 2025-03-10T18:35:07.879Z,1741631707.879 [Supervisor](INFO): Main Thread ID is 3600 2025-03-10T18:35:07.879Z,1741631707.879 [Supervisor](DEBUG): Running supervisor. 2025-03-10T18:35:07.880Z,1741631707.880 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4083 2025-03-10T18:35:07.881Z,1741631707.881 [CommandExec](INFO): Initializing the command executive. 2025-03-10T18:35:07.882Z,1741631707.882 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4084 2025-03-10T18:35:07.884Z,1741631707.884 [controlThread ThreadHandler](INFO): Handler Thread ID is 4085 2025-03-10T18:35:07.885Z,1741631707.885 [controlThread](DEBUG): Initializing ControlThread 2025-03-10T18:35:07.886Z,1741631707.886 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-10T18:35:07.887Z,1741631707.887 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-10T18:35:07.888Z,1741631707.888 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-10T18:35:07.888Z,1741631707.888 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-10T18:35:07.890Z,1741631707.890 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-10T18:35:07.891Z,1741631707.891 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-10T18:35:07.897Z,1741631707.897 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-10T18:35:07.898Z,1741631707.898 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-10T18:35:07.898Z,1741631707.898 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-10T18:35:07.898Z,1741631707.898 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-10T18:35:07.899Z,1741631707.899 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-10T18:35:07.904Z,1741631707.904 [SBIT](INFO): Initialize SBIT Component. 2025-03-10T18:35:07.904Z,1741631707.904 [SBIT](IMPORTANT): git: 2025-03-10 2025-03-10T18:35:07.904Z,1741631707.904 [SBIT](INFO): git hash: ed984fb97dc40cbcfe09dec37ea56a9b8070d839 2025-03-10T18:35:07.905Z,1741631707.905 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-10T18:35:07.906Z,1741631707.906 [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-03-10T18:35:07.907Z,1741631707.907 [SBIT](INFO): Beginning SBIT in 53.000000 seconds. 2025-03-10T18:35:07.908Z,1741631707.908 [IBIT](INFO): Initialize IBIT Component. 2025-03-10T18:35:07.908Z,1741631707.908 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-10T18:35:07.909Z,1741631707.909 [logger ThreadHandler](INFO): Handler Thread ID is 4086 2025-03-10T18:35:07.919Z,1741631707.919 [CBIT](DEBUG): Initialized mux pins. 2025-03-10T18:35:07.920Z,1741631707.920 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-10T18:35:07.928Z,1741631707.928 [DAT ThreadHandler](INFO): Handler Thread ID is 4087 2025-03-10T18:35:07.929Z,1741631707.929 [DAT](INFO): Powering up 2025-03-10T18:35:07.929Z,1741631707.929 [DAT](DEBUG): Initializing DAT. 2025-03-10T18:35:07.933Z,1741631707.933 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4089 2025-03-10T18:35:07.935Z,1741631707.935 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-10T18:35:07.944Z,1741631707.944 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-10T18:35:07.944Z,1741631707.944 [CBIT](DEBUG): Initializing heartbeat. 2025-03-10T18:35:07.945Z,1741631707.945 [Onboard ThreadHandler](INFO): Handler Thread ID is 4090 2025-03-10T18:35:07.965Z,1741631707.965 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4091 2025-03-10T18:35:07.976Z,1741631707.976 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4092 2025-03-10T18:35:07.977Z,1741631707.977 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-10T18:35:07.981Z,1741631707.981 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4094 2025-03-10T18:35:07.985Z,1741631707.985 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4096 2025-03-10T18:35:07.988Z,1741631707.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-10T18:35:07.988Z,1741631707.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-10T18:35:07.988Z,1741631707.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-10T18:35:07.988Z,1741631707.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-10T18:35:07.988Z,1741631707.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-10T18:35:07.988Z,1741631707.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-10T18:35:07.989Z,1741631707.989 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-10T18:35:07.989Z,1741631707.989 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-10T18:35:08.004Z,1741631708.004 [WetLabsBB2FL](INFO): Powering up 2025-03-10T18:35:08.015Z,1741631708.015 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-10T18:35:08.055Z,1741631708.055 [CBIT](DEBUG): Backplane powered. 2025-03-10T18:35:08.056Z,1741631708.056 [GFScanner](DEBUG): Initializing GFScanner 2025-03-10T18:35:08.056Z,1741631708.056 [GFScanner](DEBUG): Deactivating GF circuits. 2025-03-10T18:35:08.060Z,1741631708.060 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-10T18:35:08.061Z,1741631708.061 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-10T18:35:08.061Z,1741631708.061 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-10T18:35:08.063Z,1741631708.063 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-10T18:35:08.116Z,1741631708.116 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-10T18:35:08.136Z,1741631708.136 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-10T18:35:08.156Z,1741631708.156 [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-10T18:35:08.157Z,1741631708.157 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-10T18:35:08.157Z,1741631708.157 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-10T18:35:08.158Z,1741631708.158 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-10T18:35:08.368Z,1741631708.368 [Radio_Surface](INFO): Powering up 2025-03-10T18:35:08.481Z,1741631708.481 [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-10T18:35:08.517Z,1741631708.517 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-10T18:35:08.518Z,1741631708.518 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-10T18:35:08.541Z,1741631708.541 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-10T18:35:08.561Z,1741631708.561 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-10T18:35:08.580Z,1741631708.580 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-10T18:35:08.602Z,1741631708.602 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-10T18:35:08.605Z,1741631708.605 [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-10T18:35:08.610Z,1741631708.610 [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-03-10T18:35:08.635Z,1741631708.635 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-10T18:35:08.709Z,1741631708.709 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m 2025-03-10T18:35:08.710Z,1741631708.710 [Power24vConverter](INFO): Powering up. 2025-03-10T18:35:08.711Z,1741631708.711 [Sonardyne_Nano](INFO): Initializing. 2025-03-10T18:35:08.732Z,1741631708.732 [CANONSampler](INFO): Powering down 2025-03-10T18:35:08.764Z,1741631708.764 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-03-10T18:35:08.793Z,1741631708.793 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-10T18:35:08.800Z,1741631708.800 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-10T18:35:08.801Z,1741631708.801 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-10T18:35:08.808Z,1741631708.808 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-10T18:35:08.809Z,1741631708.809 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-10T18:35:08.816Z,1741631708.816 [MassServo](DEBUG): Initializing MassServo. 2025-03-10T18:35:08.817Z,1741631708.817 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-10T18:35:08.824Z,1741631708.824 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-10T18:35:08.825Z,1741631708.825 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-10T18:35:08.832Z,1741631708.832 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-10T18:35:09.049Z,1741631709.049 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-10T18:35:09.050Z,1741631709.050 [DropWeight] Hardware Fault, FailCount= 1 2025-03-10T18:35:09.050Z,1741631709.050 [DropWeight](ERROR): Hardware Fault 2025-03-10T18:35:09.091Z,1741631709.091 [CommandExec](FAULT): Scheduling is paused 2025-03-10T18:35:09.100Z,1741631709.100 [CBIT](INFO): Critical error at 20250310T183509 2025-03-10T18:35:09.100Z,1741631709.100 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-03-10T18:35:09.102Z,1741631709.102 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-10T18:35:09.103Z,1741631709.103 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-10T18:35:09.450Z,1741631709.450 [CBIT](INFO): Critical error at 20250310T183509 2025-03-10T18:35:10.092Z,1741631710.092 [WetLabsBB2FL](INFO): Powering down 2025-03-10T18:35:10.638Z,1741631710.638 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-03-10T18:35:11.924Z,1741631711.924 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2025-03-10T18:35:14.497Z,1741631714.497 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-03-10T18:35:19.522Z,1741631719.522 [DAT](INFO): DAT read: 2025-03-10T18:35:19.523Z,1741631719.523 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-03-10T18:35:21.032Z,1741631721.032 [DAT](INFO): DAT read: MF Frequency Band 2025-03-10T18:35:21.034Z,1741631721.034 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-03-10T18:35:21.034Z,1741631721.034 [DAT](INFO): DAT read: Mar 7 2025 01:11:09 2025-03-10T18:35:22.044Z,1741631722.044 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-03-10T18:35:22.293Z,1741631722.293 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2025-03-10T18:35:22.293Z,1741631722.293 [DAT](INFO): commRate: 1200 2025-03-10T18:35:24.360Z,1741631724.360 [DAT](INFO): entering command mode 2025-03-10T18:35:24.561Z,1741631724.561 [DAT](INFO): DAT read: 2025-03-10T18:35:24.561Z,1741631724.561 [DAT](INFO): DAT read: us:1> 2025-03-10T18:35:24.562Z,1741631724.562 [DAT](INFO): unknown deviceResponse_: us:1> 2025-03-10T18:35:24.562Z,1741631724.562 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:24.812Z,1741631724.812 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:25.064Z,1741631725.064 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:25.316Z,1741631725.316 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:25.568Z,1741631725.568 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:25.820Z,1741631725.820 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:26.072Z,1741631726.072 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:26.324Z,1741631726.324 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:26.576Z,1741631726.576 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:26.828Z,1741631726.828 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:27.080Z,1741631727.080 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:27.332Z,1741631727.332 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:27.584Z,1741631727.584 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:27.836Z,1741631727.836 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:28.088Z,1741631728.088 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:28.342Z,1741631728.342 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:28.592Z,1741631728.592 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:28.844Z,1741631728.844 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:29.096Z,1741631729.096 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:29.348Z,1741631729.348 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:29.600Z,1741631729.600 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:29.852Z,1741631729.852 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:30.104Z,1741631730.104 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:30.356Z,1741631730.356 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:30.608Z,1741631730.608 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:30.860Z,1741631730.860 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:31.112Z,1741631731.112 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:31.364Z,1741631731.364 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:31.616Z,1741631731.616 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:31.868Z,1741631731.868 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:32.120Z,1741631732.120 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:32.372Z,1741631732.372 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:32.624Z,1741631732.624 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:32.876Z,1741631732.876 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:33.128Z,1741631733.128 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:33.380Z,1741631733.380 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:33.632Z,1741631733.632 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:33.884Z,1741631733.884 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:34.136Z,1741631734.136 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:34.388Z,1741631734.388 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:34.640Z,1741631734.640 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:34.892Z,1741631734.892 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:35.144Z,1741631735.144 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:35.396Z,1741631735.396 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:35.648Z,1741631735.648 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:35.900Z,1741631735.900 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:36.152Z,1741631736.152 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:36.404Z,1741631736.404 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:36.656Z,1741631736.656 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:36.897Z,1741631736.897 [NAL9602](INFO): Powering up NAL9602 2025-03-10T18:35:36.908Z,1741631736.908 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:37.160Z,1741631737.160 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:37.412Z,1741631737.412 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:37.664Z,1741631737.664 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:37.916Z,1741631737.916 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:38.168Z,1741631738.168 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:38.420Z,1741631738.420 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:38.672Z,1741631738.672 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:38.924Z,1741631738.924 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:39.176Z,1741631739.176 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:39.428Z,1741631739.428 [DAT](DEBUG): checking for command mode acknowledgment 2025-03-10T18:35:39.428Z,1741631739.428 [DAT](FAULT): failed to enter command mode 2025-03-10T18:35:39.732Z,1741631739.732 [DAT](INFO): entering command mode 2025-03-10T18:35:39.932Z,1741631739.932 [DAT](INFO): DAT read: user:1> 2025-03-10T18:35:39.933Z,1741631739.933 [DAT](INFO): DAT read: Command '+++' not found 2025-03-10T18:35:39.933Z,1741631739.933 [DAT](INFO): DAT read: Error 2025-03-10T18:35:39.934Z,1741631739.934 [DAT](INFO): setting verbose to 3 2025-03-10T18:35:40.184Z,1741631740.184 [DAT](INFO): DAT read: user:2> 2025-03-10T18:35:40.185Z,1741631740.185 [DAT](INFO): DAT read: Verbose | 3 2025-03-10T18:35:40.186Z,1741631740.186 [DAT](INFO): set verbose to 3 2025-03-10T18:35:40.186Z,1741631740.186 [DAT](INFO): setting DatVerbose to 27440 2025-03-10T18:35:40.436Z,1741631740.436 [DAT](INFO): DAT read: user:3> 2025-03-10T18:35:40.437Z,1741631740.437 [DAT](INFO): DAT read: DatVerbose | 27440 2025-03-10T18:35:40.437Z,1741631740.437 [DAT](INFO): set DatVerbose to 27440 2025-03-10T18:35:40.438Z,1741631740.438 [DAT](INFO): setting transmit power to 8 2025-03-10T18:35:40.688Z,1741631740.688 [DAT](INFO): DAT read: user:4> 2025-03-10T18:35:40.689Z,1741631740.689 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-03-10T18:35:40.690Z,1741631740.690 [DAT](INFO): set transmit power to 8 2025-03-10T18:35:40.690Z,1741631740.690 [DAT](INFO): setting local address to 8 2025-03-10T18:35:40.940Z,1741631740.940 [DAT](INFO): DAT read: user:5> 2025-03-10T18:35:40.941Z,1741631740.941 [DAT](INFO): DAT read: LocalAddr | 8 2025-03-10T18:35:40.941Z,1741631740.941 [DAT](INFO): set local address to 8 2025-03-10T18:35:40.942Z,1741631740.942 [DAT](INFO): Setting time to: 18:35:40 And date to:3/10/2025 2025-03-10T18:35:41.192Z,1741631741.192 [DAT](INFO): DAT read: user:6> 2025-03-10T18:35:41.193Z,1741631741.193 [DAT](INFO): DAT read: Mon Mar 10, 2025 18:35:40 2025-03-10T18:35:41.193Z,1741631741.193 [DAT](INFO): Local DAT time set to Mon Mar 10, 2025 18:35:40 2025-03-10T18:35:47.805Z,1741631747.805 [NAL9602](INFO): NAL9602 initialized 2025-03-10T18:36:01.593Z,1741631761.593 [SBIT](IMPORTANT): Beginning Startup BIT 2025-03-10T18:36:01.598Z,1741631761.598 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-03-10T18:36:06.064Z,1741631766.064 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.001613 CHAN A1 (24V): 0.047554 CHAN A2 (12V): -0.002365 CHAN A3 (5V): -0.001466 CHAN B0 (3.3V): -0.000093 CHAN B1 (3.15aV): 0.000087 CHAN B2 (3.15bV): -0.000134 CHAN B3 (GND): -0.000007 OPEN: -0.000414 Full Scale: +/- 1 mA 2025-03-10T18:36:14.662Z,1741631774.662 [SBIT](FAULT): Mass: EXPECTED:0.013473 ACTUAL:0.009184 2025-03-10T18:36:14.662Z,1741631774.662 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.797676 2025-03-10T18:36:55.347Z,1741631815.347 [SBIT](CRITICAL): SBIT FAILED 2025-03-10T18:36:55.347Z,1741631815.347 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-03-10T18:36:55.348Z,1741631815.348 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count; 2025-03-10T18:36:55.348Z,1741631815.348 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2025-03-10T18:36:55.348Z,1741631815.348 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2025-03-10T18:36:55.348Z,1741631815.348 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool; 2025-03-10T18:36:55.348Z,1741631815.348 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool; 2025-03-10T18:36:55.348Z,1741631815.348 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=355.541360 cubic_centimeter; 2025-03-10T18:36:55.349Z,1741631815.349 [SBIT](IMPORTANT): VerticalControl.massDefault=8.472520 millimeter; 2025-03-10T18:36:55.350Z,1741631815.350 [CBIT](INFO): Critical error at 20250310T183655 2025-03-10T18:36:55.754Z,1741631815.754 [MissionManager](IMPORTANT): Started mission Startup 2025-03-10T18:36:55.754Z,1741631815.754 [Startup] Running Loop=1 2025-03-10T18:36:55.754Z,1741631815.754 [Startup](DEBUG): Aggregate::initialize Startup 2025-03-10T18:36:55.754Z,1741631815.754 [Startup:A.GoToSurface] Running Loop=1 2025-03-10T18:36:55.754Z,1741631815.754 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-10T18:36:55.755Z,1741631815.755 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-10T18:36:55.756Z,1741631815.756 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-10T18:36:55.756Z,1741631815.756 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-10T18:36:55.756Z,1741631815.756 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-10T18:36:55.757Z,1741631815.757 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-10T18:36:55.757Z,1741631815.757 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-10T18:36:55.759Z,1741631815.759 [Startup:StartupSatComms] Running Loop=1 2025-03-10T18:36:55.759Z,1741631815.759 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-03-10T18:36:55.759Z,1741631815.759 [Startup:StartupSatComms:A] Running Loop=1 2025-03-10T18:36:56.146Z,1741631816.146 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-03-10T18:36:57.004Z,1741631817.004 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:37:23.667Z,1741631843.667 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:37:46.848Z,1741631866.848 [CommandExec](IMPORTANT): got command gfscan 2025-03-10T18:37:47.216Z,1741631867.216 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-03-10T18:37:49.922Z,1741631869.922 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:37:51.077Z,1741631871.077 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004245 2025-03-10T18:37:51.695Z,1741631871.695 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.001132 CHAN A1 (24V): 4.657929 CHAN A2 (12V): -0.002064 CHAN A3 (5V): -0.001241 CHAN B0 (3.3V): 0.000304 CHAN B1 (3.15aV): -0.000307 CHAN B2 (3.15bV): -0.000095 CHAN B3 (GND): -0.000086 OPEN: -0.000168 Full Scale: +/- 1 mA 2025-03-10T18:37:55.937Z,1741631875.937 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-10T18:36:55.8Z 2025-03-10T18:37:55.937Z,1741631875.937 [Startup:StartupSatComms:A] Stopped 2025-03-10T18:37:55.937Z,1741631875.937 [Startup:StartupSatComms:B] Running Loop=1 2025-03-10T18:37:56.338Z,1741631876.338 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-10T18:37:57.942Z,1741631877.942 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:38:03.641Z,1741631883.641 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20250310T181114/Courier0000.lzma 2025-03-10T18:38:04.642Z,1741631884.642 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181114/Courier0000.lzma.bak 2025-03-10T18:38:04.642Z,1741631884.642 [DataOverHttps](INFO): SBD MOMSN=24149651 2025-03-10T18:38:08.080Z,1741631888.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-03-10T18:38:08.080Z,1741631888.080 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:38:08.268Z,1741631888.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:38:08.632Z,1741631888.632 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:38:08.632Z,1741631888.632 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-03-10T18:38:13.212Z,1741631893.212 [CommandExec](IMPORTANT): got command gfscan 2025-03-10T18:38:13.492Z,1741631893.492 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-03-10T18:38:16.526Z,1741631896.526 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:38:17.964Z,1741631897.964 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.002415 CHAN A1 (24V): 0.047865 CHAN A2 (12V): -0.002529 CHAN A3 (5V): -0.001056 CHAN B0 (3.3V): -0.000345 CHAN B1 (3.15aV): -0.000797 CHAN B2 (3.15bV): -0.000267 CHAN B3 (GND): -0.000045 OPEN: -0.000226 Full Scale: +/- 1 mA 2025-03-10T18:38:20.867Z,1741631900.867 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T181114/Courier0004.lzma 2025-03-10T18:38:21.869Z,1741631901.869 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181114/Courier0004.lzma.bak 2025-03-10T18:38:21.869Z,1741631901.869 [DataOverHttps](INFO): SBD MOMSN=24149656 2025-03-10T18:38:35.514Z,1741631915.514 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:38:38.004Z,1741631918.004 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250310T181652/Courier0000.lzma 2025-03-10T18:38:39.005Z,1741631919.005 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181652/Courier0000.lzma.bak 2025-03-10T18:38:39.005Z,1741631919.005 [DataOverHttps](INFO): SBD MOMSN=24149658 2025-03-10T18:38:53.805Z,1741631933.805 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-03-10T18:38:54.924Z,1741631934.924 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:38:55.016Z,1741631935.016 [DataOverHttps](INFO): Sending 315 bytes from file Logs/20250310T181652/Courier0004.lzma 2025-03-10T18:38:56.017Z,1741631936.017 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181652/Courier0004.lzma.bak 2025-03-10T18:38:56.017Z,1741631936.017 [DataOverHttps](INFO): SBD MOMSN=24149666 2025-03-10T18:38:56.154Z,1741631936.154 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-10T18:37:55.9Z 2025-03-10T18:38:56.154Z,1741631936.154 [Startup:StartupSatComms:B] Stopped 2025-03-10T18:38:56.154Z,1741631936.154 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-03-10T18:38:56.154Z,1741631936.154 [Startup:StartupSatComms] Stopped 2025-03-10T18:38:56.154Z,1741631936.154 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-03-10T18:38:56.155Z,1741631936.155 [Startup](INFO): Completed Startup 2025-03-10T18:38:56.155Z,1741631936.155 [MissionManager](INFO): Startup is completed. 2025-03-10T18:38:56.155Z,1741631936.155 [MissionManager](INFO): Uninitializing Mission Startup 2025-03-10T18:38:56.156Z,1741631936.156 [Startup] Stopped 2025-03-10T18:38:56.156Z,1741631936.156 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-03-10T18:38:56.156Z,1741631936.156 [Startup:A.GoToSurface] Stopped 2025-03-10T18:38:56.156Z,1741631936.156 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-10T18:38:56.548Z,1741631936.548 [MissionManager](IMPORTANT): Started mission Default 2025-03-10T18:38:56.548Z,1741631936.548 [Default] Running Loop=1 2025-03-10T18:38:56.548Z,1741631936.548 [Default](DEBUG): Aggregate::initialize Default 2025-03-10T18:38:56.548Z,1741631936.548 [Default:B.GoToSurface] Running Loop=1 2025-03-10T18:38:56.548Z,1741631936.548 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-10T18:38:56.549Z,1741631936.549 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-10T18:38:56.549Z,1741631936.549 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-10T18:38:56.549Z,1741631936.549 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-10T18:38:56.549Z,1741631936.549 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-10T18:38:56.550Z,1741631936.550 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-10T18:38:56.550Z,1741631936.550 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-10T18:38:56.550Z,1741631936.550 [Default:A.Wait] Running Loop=1 2025-03-10T18:38:56.550Z,1741631936.550 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:39:07.129Z,1741631947.129 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadB3 2025-03-10T18:39:09.870Z,1741631949.870 [Default:A.Wait](INFO): Done Waiting. 2025-03-10T18:39:09.870Z,1741631949.870 [Default:A.Wait] Stopped 2025-03-10T18:39:09.870Z,1741631949.870 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:39:10.312Z,1741631950.312 [Default:CheckIn] Running Loop=1 2025-03-10T18:39:10.312Z,1741631950.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T18:39:10.312Z,1741631950.312 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T18:39:10.352Z,1741631950.352 [CommandExec](IMPORTANT): got command gfscan 2025-03-10T18:39:10.464Z,1741631950.464 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-03-10T18:39:10.698Z,1741631950.698 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-10T18:39:13.895Z,1741631953.895 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:39:14.871Z,1741631954.871 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.002866 CHAN A1 (24V): 0.047727 CHAN A2 (12V): -0.001886 CHAN A3 (5V): -0.001800 CHAN B0 (3.3V): -0.000511 CHAN B1 (3.15aV): -0.000423 CHAN B2 (3.15bV): -0.000026 CHAN B3 (GND): -0.000042 OPEN: -0.000692 Full Scale: +/- 1 mA 2025-03-10T18:39:33.285Z,1741631973.285 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:39:46.945Z,1741631986.945 [CommandExec](IMPORTANT): got command ! echo d > /dev/loadB3 2025-03-10T18:39:52.274Z,1741631992.274 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:40:10.050Z,1741632010.050 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:40:24.651Z,1741632024.651 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-03-10T18:40:51.673Z,1741632051.673 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-10T18:41:09.112Z,1741632069.112 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-03-10T18:41:09.112Z,1741632069.112 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:41:09.260Z,1741632069.260 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:41:09.696Z,1741632069.696 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:41:09.696Z,1741632069.696 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-03-10T18:41:20.013Z,1741632080.013 [BPC1](ERROR): BPC1B failed to parse battery stick message. 2025-03-10T18:41:44.305Z,1741632104.305 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:42:04.539Z,1741632124.539 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-03-10T18:42:20.667Z,1741632140.667 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:44:10.109Z,1741632250.109 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-03-10T18:44:10.109Z,1741632250.109 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:44:10.256Z,1741632250.256 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:44:10.516Z,1741632250.516 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T18:39:10.3Z 2025-03-10T18:44:10.516Z,1741632250.516 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:44:10.516Z,1741632250.516 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-10T18:44:10.676Z,1741632250.676 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:44:10.676Z,1741632250.676 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-03-10T18:44:10.934Z,1741632250.934 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-10T18:44:14.308Z,1741632254.308 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-03-10T18:44:17.764Z,1741632257.764 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250310T183456/Courier0000.lzma 2025-03-10T18:44:18.765Z,1741632258.765 [DataOverHttps](INFO): Moved sent file to Logs/20250310T183456/Courier0000.lzma.bak 2025-03-10T18:44:18.765Z,1741632258.765 [DataOverHttps](INFO): SBD MOMSN=24149690 2025-03-10T18:44:34.731Z,1741632274.731 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T183456/Courier0004.lzma 2025-03-10T18:44:35.734Z,1741632275.734 [DataOverHttps](INFO): Moved sent file to Logs/20250310T183456/Courier0004.lzma.bak 2025-03-10T18:44:35.734Z,1741632275.734 [DataOverHttps](INFO): SBD MOMSN=24149696 2025-03-10T18:44:51.776Z,1741632291.776 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20250310T174515/Express0005.lzma 2025-03-10T18:44:52.777Z,1741632292.777 [DataOverHttps](INFO): Moved sent file to Logs/20250310T174515/Express0005.lzma.bak 2025-03-10T18:44:52.777Z,1741632292.777 [DataOverHttps](INFO): SBD MOMSN=24149698 2025-03-10T18:45:08.449Z,1741632308.449 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:45:08.729Z,1741632308.729 [DataOverHttps](INFO): Sending 735 bytes from file Logs/20250310T181114/Express0001.lzma 2025-03-10T18:45:09.730Z,1741632309.730 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181114/Express0001.lzma.bak 2025-03-10T18:45:09.730Z,1741632309.730 [DataOverHttps](INFO): SBD MOMSN=24149705 2025-03-10T18:45:25.881Z,1741632325.881 [DataOverHttps](INFO): Sending 555 bytes from file Logs/20250310T181114/Express0005.lzma 2025-03-10T18:45:26.881Z,1741632326.881 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181114/Express0005.lzma.bak 2025-03-10T18:45:26.881Z,1741632326.881 [DataOverHttps](INFO): SBD MOMSN=24149725 2025-03-10T18:45:42.774Z,1741632342.774 [DataOverHttps](INFO): Sending 975 bytes from file Logs/20250310T181652/Express0001.lzma 2025-03-10T18:45:43.773Z,1741632343.773 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181652/Express0001.lzma.bak 2025-03-10T18:45:43.773Z,1741632343.773 [DataOverHttps](INFO): SBD MOMSN=24149761 2025-03-10T18:45:48.913Z,1741632348.913 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:45:48.915Z,1741632348.915 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-10T18:45:48.915Z,1741632348.915 [NAL9602] Data Fault, FailCount= 1 2025-03-10T18:45:48.915Z,1741632348.915 [NAL9602](ERROR): Data Fault 2025-03-10T18:45:49.116Z,1741632349.116 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-10T18:45:49.325Z,1741632349.325 [NAL9602](INFO): Powering down 2025-03-10T18:45:50.200Z,1741632350.200 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-10T18:45:50.200Z,1741632350.200 [NAL9602] No Fault, FailCount= 1 2025-03-10T18:45:59.844Z,1741632359.844 [DataOverHttps](INFO): Sending 2083 bytes from file Logs/20250310T181652/Express0005.lzma 2025-03-10T18:46:00.842Z,1741632360.842 [DataOverHttps](INFO): Moved sent file to Logs/20250310T181652/Express0005.lzma.bak 2025-03-10T18:46:00.842Z,1741632360.842 [DataOverHttps](INFO): SBD MOMSN=24149787 2025-03-10T18:46:16.761Z,1741632376.761 [DataOverHttps](INFO): Sending 1046 bytes from file Logs/20250310T183456/Express0001.lzma 2025-03-10T18:46:17.761Z,1741632377.761 [DataOverHttps](INFO): Moved sent file to Logs/20250310T183456/Express0001.lzma.bak 2025-03-10T18:46:17.761Z,1741632377.761 [DataOverHttps](INFO): SBD MOMSN=24149843 2025-03-10T18:46:19.489Z,1741632379.489 [NAL9602](INFO): Powering up NAL9602 2025-03-10T18:46:30.507Z,1741632390.507 [NAL9602](INFO): NAL9602 initialized 2025-03-10T18:46:33.712Z,1741632393.712 [DataOverHttps](INFO): Sending 465 bytes from file Logs/20250310T183456/Express0005.lzma 2025-03-10T18:46:34.713Z,1741632394.713 [DataOverHttps](INFO): Moved sent file to Logs/20250310T183456/Express0005.lzma.bak 2025-03-10T18:46:34.713Z,1741632394.713 [DataOverHttps](INFO): SBD MOMSN=24149869 2025-03-10T18:46:36.065Z,1741632396.065 [Default:CheckIn:Read_Iridium] Stopped 2025-03-10T18:46:36.065Z,1741632396.065 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-10T18:46:36.065Z,1741632396.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-10T18:47:11.353Z,1741632431.353 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-03-10T18:47:11.353Z,1741632431.353 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:47:11.508Z,1741632431.508 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:47:11.780Z,1741632431.780 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:47:11.780Z,1741632431.780 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-03-10T18:47:26.156Z,1741632446.156 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-03-10T18:47:33.413Z,1741632453.413 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:47:37.922Z,1741632457.922 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-03-10T18:47:52.807Z,1741632472.807 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:48:12.198Z,1741632492.198 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:48:31.590Z,1741632511.590 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:48:51.387Z,1741632531.387 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:49:10.778Z,1741632550.778 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:49:30.575Z,1741632570.575 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-03-10T18:49:49.659Z,1741632589.659 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:50:09.138Z,1741632609.138 [CBIT](INFO): Clearing failed state for component DropWeight 2025-03-10T18:50:09.139Z,1741632609.139 [DropWeight] No Fault, FailCount= 1 2025-03-10T18:50:12.220Z,1741632612.220 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-03-10T18:50:12.220Z,1741632612.220 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:50:12.384Z,1741632612.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:50:12.772Z,1741632612.772 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:50:12.772Z,1741632612.772 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-03-10T18:51:36.686Z,1741632696.686 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-10T18:51:36.687Z,1741632696.687 [Default:CheckIn:C.Wait] Stopped 2025-03-10T18:51:36.687Z,1741632696.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-10T18:51:36.687Z,1741632696.687 [Default:CheckIn:D] Running Loop=1 2025-03-10T18:51:37.080Z,1741632697.080 [Default:CheckIn:D] Stopped 2025-03-10T18:51:37.080Z,1741632697.080 [Default:CheckIn:E] Running Loop=1 2025-03-10T18:51:37.496Z,1741632697.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.675538 min 2025-03-10T18:51:37.496Z,1741632697.496 [Default:CheckIn:E] Stopped 2025-03-10T18:51:37.496Z,1741632697.496 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-10T18:51:37.496Z,1741632697.496 [Default:CheckIn] Stopped 2025-03-10T18:51:37.496Z,1741632697.496 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:51:37.496Z,1741632697.496 [Default:CheckIn](INFO): Running loop #2 2025-03-10T18:51:37.497Z,1741632697.497 [Default:CheckIn] Running Loop=2 2025-03-10T18:51:37.497Z,1741632697.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-10T18:51:37.497Z,1741632697.497 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-10T18:53:13.324Z,1741632793.324 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-03-10T18:53:13.324Z,1741632793.324 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:53:13.468Z,1741632793.468 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:53:13.816Z,1741632793.816 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:53:13.816Z,1741632793.816 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-03-10T18:53:26.610Z,1741632806.610 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-03-10T18:55:21.787Z,1741632921.787 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:55:34.713Z,1741632934.713 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-03-10T18:56:08.693Z,1741632968.693 [CommandExec](IMPORTANT): got command restart application 2025-03-10T18:56:09.696Z,1741632969.696 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:09.696Z,1741632969.696 [CommandExec](INFO): Uninitializing the command executive. 2025-03-10T18:56:09.696Z,1741632969.696 [CommandExec](INFO): Uninitializing the command scheduler. 2025-03-10T18:56:09.696Z,1741632969.696 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:09.857Z,1741632969.857 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-03-10T18:56:09.857Z,1741632969.857 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-03-10T18:56:09.857Z,1741632969.857 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:09.858Z,1741632969.858 [NavChartDb](INFO): Join timeout helper Thread ID is 4129 2025-03-10T18:56:10.016Z,1741632970.016 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:10.016Z,1741632970.016 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:10.024Z,1741632970.024 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-03-10T18:56:10.024Z,1741632970.024 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:10.024Z,1741632970.024 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4130 2025-03-10T18:56:10.524Z,1741632970.524 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:10.524Z,1741632970.524 [WetLabsBB2FL](INFO): Powering down 2025-03-10T18:56:10.525Z,1741632970.525 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:10.527Z,1741632970.527 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-03-10T18:56:10.528Z,1741632970.528 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:10.528Z,1741632970.528 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4131 2025-03-10T18:56:11.248Z,1741632971.248 [CTD_Seabird](INFO): Powering down 2025-03-10T18:56:11.260Z,1741632971.260 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:11.260Z,1741632971.260 [CTD_Seabird](INFO): Powering down 2025-03-10T18:56:11.271Z,1741632971.271 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:11.272Z,1741632971.272 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-03-10T18:56:11.272Z,1741632971.272 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:11.272Z,1741632971.272 [Radio_Surface](INFO): Join timeout helper Thread ID is 4132 2025-03-10T18:56:11.544Z,1741632971.544 [Radio_Surface](INFO): Powering down 2025-03-10T18:56:11.545Z,1741632971.545 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:11.545Z,1741632971.545 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:11.560Z,1741632971.560 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-03-10T18:56:11.560Z,1741632971.560 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:11.560Z,1741632971.560 [Onboard](INFO): Join timeout helper Thread ID is 4133 2025-03-10T18:56:12.448Z,1741632972.448 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-03-10T18:56:14.016Z,1741632974.016 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:14.016Z,1741632974.016 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.028Z,1741632974.028 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-03-10T18:56:14.028Z,1741632974.028 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.029Z,1741632974.029 [DataOverHttps](INFO): Join timeout helper Thread ID is 4134 2025-03-10T18:56:14.281Z,1741632974.281 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-03-10T18:56:14.281Z,1741632974.281 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-10T18:56:14.428Z,1741632974.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-10T18:56:14.456Z,1741632974.456 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:14.456Z,1741632974.456 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.480Z,1741632974.480 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-03-10T18:56:14.480Z,1741632974.480 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.481Z,1741632974.481 [DAT](INFO): Join timeout helper Thread ID is 4135 2025-03-10T18:56:14.644Z,1741632974.644 [DAT](INFO): Powering down 2025-03-10T18:56:14.718Z,1741632974.718 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:14.718Z,1741632974.718 [DAT](INFO): Powering down 2025-03-10T18:56:14.719Z,1741632974.719 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.733Z,1741632974.733 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-03-10T18:56:14.733Z,1741632974.733 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.734Z,1741632974.734 [logger](INFO): Join timeout helper Thread ID is 4136 2025-03-10T18:56:14.740Z,1741632974.740 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:14.741Z,1741632974.741 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.756Z,1741632974.756 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-03-10T18:56:14.756Z,1741632974.756 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.757Z,1741632974.757 [CommandLine](INFO): Join timeout helper Thread ID is 4137 2025-03-10T18:56:14.792Z,1741632974.792 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:14.792Z,1741632974.792 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.796Z,1741632974.796 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-03-10T18:56:14.796Z,1741632974.796 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.797Z,1741632974.797 [CommandExec](INFO): Join timeout helper Thread ID is 4138 2025-03-10T18:56:14.798Z,1741632974.798 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-03-10T18:56:14.798Z,1741632974.798 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:14.798Z,1741632974.798 [controlThread](INFO): Join timeout helper Thread ID is 4139 2025-03-10T18:56:14.851Z,1741632974.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-10T18:56:14.851Z,1741632974.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-03-10T18:56:14.853Z,1741632974.853 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-10T18:56:14.853Z,1741632974.853 [controlThread](DEBUG): Uninitializing ControlThread 2025-03-10T18:56:14.854Z,1741632974.854 [AHRS_M2](INFO): Powering down 2025-03-10T18:56:14.925Z,1741632974.925 [NAL9602](INFO): Powering down 2025-03-10T18:56:14.926Z,1741632974.926 [Sonardyne_Nano](INFO): Powering down 2025-03-10T18:56:15.045Z,1741632975.045 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-03-10T18:56:15.046Z,1741632975.046 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-03-10T18:56:15.046Z,1741632975.046 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-03-10T18:56:15.047Z,1741632975.047 [MissionManager](INFO): Uninitializing Mission Default 2025-03-10T18:56:15.047Z,1741632975.047 [Default] Stopped 2025-03-10T18:56:15.047Z,1741632975.047 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-10T18:56:15.047Z,1741632975.047 [Default:B.GoToSurface] Stopped 2025-03-10T18:56:15.047Z,1741632975.047 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-10T18:56:15.047Z,1741632975.047 [Default:CheckIn] Stopped 2025-03-10T18:56:15.048Z,1741632975.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-10T18:56:15.048Z,1741632975.048 [Default:CheckIn:Read_GPS] Stopped 2025-03-10T18:56:15.051Z,1741632975.051 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-03-10T18:56:15.051Z,1741632975.051 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-03-10T18:56:15.051Z,1741632975.051 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-03-10T18:56:15.052Z,1741632975.052 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-03-10T18:56:15.052Z,1741632975.052 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-03-10T18:56:15.052Z,1741632975.052 [BuoyancyServo](INFO): Powering down 2025-03-10T18:56:15.064Z,1741632975.064 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-10T18:56:15.064Z,1741632975.064 [ElevatorServo](INFO): Powering down 2025-03-10T18:56:15.065Z,1741632975.065 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-10T18:56:15.065Z,1741632975.065 [MassServo](INFO): Powering down 2025-03-10T18:56:15.065Z,1741632975.065 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-03-10T18:56:15.066Z,1741632975.066 [RudderServo](INFO): Powering down 2025-03-10T18:56:15.066Z,1741632975.066 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-10T18:56:15.066Z,1741632975.066 [ThrusterHE](INFO): Powering down 2025-03-10T18:56:15.067Z,1741632975.067 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-03-10T18:56:15.068Z,1741632975.068 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-03-10T18:56:15.068Z,1741632975.068 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-03-10T18:56:15.068Z,1741632975.068 [CBIT](DEBUG): Powering off loads. 2025-03-10T18:56:15.079Z,1741632975.079 [CBIT](DEBUG): Disabling WDT. 2025-03-10T18:56:15.091Z,1741632975.091 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-10T18:56:15.092Z,1741632975.092 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-10T18:56:15.092Z,1741632975.092 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.258Z,1741632975.258 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.261Z,1741632975.261 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.268Z,1741632975.268 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.275Z,1741632975.275 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.352Z,1741632975.352 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.357Z,1741632975.357 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.383Z,1741632975.383 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-10T18:56:15.383Z,1741632975.383 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-10T18:56:15.405Z,1741632975.405 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-10T18:56:15.476Z,1741632975.476 [logger ThreadHandler](INFO): Thread cancelled.