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.