2025-03-10T20:08:50.764Z,1741637330.764 [Supervisor](DEBUG): Initializing supervisor.
2025-03-10T20:08:50.769Z,1741637330.769 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-10T20:08:50.770Z,1741637330.770 [SyncHandler](INFO): Protected caller Thread ID is 4832
2025-03-10T20:08:50.770Z,1741637330.770 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-10T20:08:50.771Z,1741637330.771 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-10T20:08:50.772Z,1741637330.772 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4833
2025-03-10T20:08:50.776Z,1741637330.776 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-10T20:08:50.795Z,1741637330.795 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-10T20:08:50.796Z,1741637330.796 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-10T20:08:50.796Z,1741637330.796 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4834
2025-03-10T20:08:50.801Z,1741637330.801 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-10T20:08:50.802Z,1741637330.802 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-10T20:08:50.802Z,1741637330.802 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4835
2025-03-10T20:08:50.804Z,1741637330.804 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-10T20:08:50.805Z,1741637330.805 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-10T20:08:50.806Z,1741637330.806 [logger ThreadHandler](INFO): Protected caller Thread ID is 4836
2025-03-10T20:08:50.809Z,1741637330.809 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-10T20:08:50.810Z,1741637330.810 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-10T20:08:50.812Z,1741637330.812 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-10T20:08:51.087Z,1741637331.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-10T20:08:51.088Z,1741637331.088 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-10T20:08:51.180Z,1741637331.180 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-10T20:08:51.721Z,1741637331.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-10T20:08:51.721Z,1741637331.721 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-10T20:08:52.095Z,1741637332.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-10T20:08:52.095Z,1741637332.095 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-10T20:08:52.203Z,1741637332.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-10T20:08:52.204Z,1741637332.204 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-10T20:08:52.638Z,1741637332.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-10T20:08:52.638Z,1741637332.638 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-10T20:08:52.880Z,1741637332.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-10T20:08:52.880Z,1741637332.880 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-10T20:08:53.420Z,1741637333.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-10T20:08:53.420Z,1741637333.420 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-10T20:08:53.569Z,1741637333.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-10T20:08:53.570Z,1741637333.570 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-10T20:08:53.661Z,1741637333.661 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-10T20:08:54.515Z,1741637334.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-10T20:08:54.516Z,1741637334.516 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-10T20:08:54.932Z,1741637334.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-10T20:08:54.933Z,1741637334.933 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-10T20:08:55.197Z,1741637335.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-10T20:08:55.198Z,1741637335.198 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-10T20:08:55.464Z,1741637335.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-10T20:08:55.465Z,1741637335.465 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-10T20:08:55.711Z,1741637335.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-10T20:08:55.712Z,1741637335.712 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-10T20:08:55.973Z,1741637335.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-10T20:08:55.975Z,1741637335.975 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2025-03-10T20:08:55.976Z,1741637335.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2025-03-10T20:08:56.073Z,1741637336.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2025-03-10T20:08:56.186Z,1741637336.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2025-03-10T20:08:56.282Z,1741637336.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2025-03-10T20:08:56.396Z,1741637336.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2025-03-10T20:08:56.501Z,1741637336.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2025-03-10T20:08:56.630Z,1741637336.630 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2025-03-10T20:08:56.797Z,1741637336.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2025-03-10T20:08:56.959Z,1741637336.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2025-03-10T20:08:57.081Z,1741637337.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2025-03-10T20:08:57.358Z,1741637337.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-10T20:08:57.360Z,1741637337.360 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2025-03-10T20:08:57.360Z,1741637337.360 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-03-10T20:08:57.369Z,1741637337.369 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-10T20:08:57.425Z,1741637337.425 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-10T20:08:57.486Z,1741637337.486 [VerticalControl] Loaded
2025-03-10T20:08:57.486Z,1741637337.486 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-10T20:08:57.489Z,1741637337.489 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-10T20:08:57.535Z,1741637337.535 [HorizontalControl] Loaded
2025-03-10T20:08:57.536Z,1741637337.536 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-10T20:08:57.538Z,1741637337.538 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-10T20:08:57.541Z,1741637337.541 [SpeedControl] Loaded
2025-03-10T20:08:57.542Z,1741637337.542 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-10T20:08:57.544Z,1741637337.544 [LoopControl](DEBUG): Construct LoopControl.
2025-03-10T20:08:57.545Z,1741637337.545 [LoopControl] Loaded
2025-03-10T20:08:57.545Z,1741637337.545 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-10T20:08:57.546Z,1741637337.546 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-10T20:08:57.546Z,1741637337.546 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-10T20:08:57.557Z,1741637337.557 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-10T20:08:57.557Z,1741637337.557 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-10T20:08:57.739Z,1741637337.739 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-10T20:08:57.739Z,1741637337.739 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-10T20:08:57.903Z,1741637337.903 [BuoyancyServo] Loaded
2025-03-10T20:08:57.904Z,1741637337.904 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-10T20:08:57.926Z,1741637337.926 [ElevatorServo] Loaded
2025-03-10T20:08:57.926Z,1741637337.926 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-10T20:08:57.948Z,1741637337.948 [MassServo] Loaded
2025-03-10T20:08:57.948Z,1741637337.948 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-10T20:08:57.968Z,1741637337.968 [RudderServo] Loaded
2025-03-10T20:08:57.968Z,1741637337.968 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-10T20:08:57.985Z,1741637337.985 [ThrusterHE] Loaded
2025-03-10T20:08:57.985Z,1741637337.985 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-10T20:08:57.986Z,1741637337.986 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-10T20:08:57.986Z,1741637337.986 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-10T20:08:58.100Z,1741637338.100 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-10T20:08:58.101Z,1741637338.101 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-10T20:08:58.114Z,1741637338.114 [NavChart] Loaded
2025-03-10T20:08:58.115Z,1741637338.115 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-10T20:08:58.120Z,1741637338.120 [UniversalFixResidualReporter] Loaded
2025-03-10T20:08:58.120Z,1741637338.120 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-10T20:08:58.121Z,1741637338.121 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-10T20:08:58.121Z,1741637338.121 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-10T20:08:58.182Z,1741637338.182 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-10T20:08:58.182Z,1741637338.182 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-10T20:08:58.670Z,1741637338.670 [AHRS_M2] Loaded
2025-03-10T20:08:58.670Z,1741637338.670 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-10T20:08:59.482Z,1741637339.482 [BPC1] Loaded
2025-03-10T20:08:59.482Z,1741637339.482 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-10T20:08:59.582Z,1741637339.582 [DAT] Loaded
2025-03-10T20:08:59.582Z,1741637339.582 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-03-10T20:08:59.583Z,1741637339.583 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094B4E0
2025-03-10T20:08:59.583Z,1741637339.583 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4915
2025-03-10T20:08:59.657Z,1741637339.657 [DataOverHttps] Loaded
2025-03-10T20:08:59.658Z,1741637339.658 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-10T20:08:59.659Z,1741637339.659 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097B4E0
2025-03-10T20:08:59.659Z,1741637339.659 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4916
2025-03-10T20:08:59.682Z,1741637339.682 [Depth_Keller] Loaded
2025-03-10T20:08:59.683Z,1741637339.683 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-10T20:08:59.687Z,1741637339.687 [DropWeight] Loaded
2025-03-10T20:08:59.688Z,1741637339.688 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-10T20:08:59.752Z,1741637339.752 [NAL9602] Loaded
2025-03-10T20:08:59.753Z,1741637339.753 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-10T20:08:59.783Z,1741637339.783 [Onboard] Loaded
2025-03-10T20:08:59.783Z,1741637339.783 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-10T20:08:59.784Z,1741637339.784 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0
2025-03-10T20:08:59.785Z,1741637339.785 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4917
2025-03-10T20:08:59.799Z,1741637339.799 [Power24vConverter] Loaded
2025-03-10T20:08:59.799Z,1741637339.799 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-10T20:08:59.815Z,1741637339.815 [Radio_Surface] Loaded
2025-03-10T20:08:59.815Z,1741637339.815 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-10T20:08:59.816Z,1741637339.816 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2025-03-10T20:08:59.817Z,1741637339.817 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4918
2025-03-10T20:08:59.832Z,1741637339.832 [Sonardyne_Nano] Loaded
2025-03-10T20:08:59.832Z,1741637339.832 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-10T20:08:59.833Z,1741637339.833 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-10T20:08:59.834Z,1741637339.834 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-10T20:08:59.886Z,1741637339.886 [DepthRateCalculator] Loaded
2025-03-10T20:08:59.886Z,1741637339.886 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-10T20:08:59.891Z,1741637339.891 [PitchRateCalculator] Loaded
2025-03-10T20:08:59.891Z,1741637339.891 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-10T20:08:59.900Z,1741637339.900 [SpeedCalculator] Loaded
2025-03-10T20:08:59.900Z,1741637339.900 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-10T20:08:59.905Z,1741637339.905 [YawRateCalculator] Loaded
2025-03-10T20:08:59.905Z,1741637339.905 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-10T20:08:59.924Z,1741637339.924 [ElevatorOffsetCalculator] Loaded
2025-03-10T20:08:59.924Z,1741637339.924 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-10T20:08:59.925Z,1741637339.925 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-10T20:08:59.925Z,1741637339.925 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-10T20:09:00.246Z,1741637340.246 [CANONSampler] Loaded
2025-03-10T20:09:00.246Z,1741637340.246 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-03-10T20:09:00.341Z,1741637340.341 [CTD_Seabird] Loaded
2025-03-10T20:09:00.341Z,1741637340.341 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-10T20:09:00.342Z,1741637340.342 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A964E0
2025-03-10T20:09:00.343Z,1741637340.343 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4919
2025-03-10T20:09:00.365Z,1741637340.365 [PAR_Licor] Loaded
2025-03-10T20:09:00.365Z,1741637340.365 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-10T20:09:00.471Z,1741637340.471 [WetLabsBB2FL] Loaded
2025-03-10T20:09:00.472Z,1741637340.472 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-10T20:09:00.473Z,1741637340.473 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC64E0
2025-03-10T20:09:00.473Z,1741637340.473 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4920
2025-03-10T20:09:00.474Z,1741637340.474 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-10T20:09:00.474Z,1741637340.474 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-10T20:09:00.533Z,1741637340.533 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-10T20:09:00.534Z,1741637340.534 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-10T20:09:00.887Z,1741637340.887 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-10T20:09:00.888Z,1741637340.888 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-10T20:09:01.053Z,1741637341.053 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-10T20:09:01.062Z,1741637341.062 [SBIT] Loaded
2025-03-10T20:09:01.062Z,1741637341.062 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-10T20:09:01.065Z,1741637341.065 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-10T20:09:01.078Z,1741637341.078 [IBIT] Loaded
2025-03-10T20:09:01.078Z,1741637341.078 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-10T20:09:01.084Z,1741637341.084 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-10T20:09:01.165Z,1741637341.165 [CBIT] Loaded
2025-03-10T20:09:01.165Z,1741637341.165 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-10T20:09:01.198Z,1741637341.198 [GFScanner] Loaded
2025-03-10T20:09:01.198Z,1741637341.198 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-10T20:09:01.198Z,1741637341.198 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-10T20:09:01.199Z,1741637341.199 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-10T20:09:01.334Z,1741637341.334 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-10T20:09:01.340Z,1741637341.340 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-10T20:09:01.343Z,1741637341.343 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-10T20:09:01.354Z,1741637341.354 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-10T20:09:01.355Z,1741637341.355 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0
2025-03-10T20:09:01.356Z,1741637341.356 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4921
2025-03-10T20:09:01.360Z,1741637341.360 [Supervisor](INFO): Main Thread ID is 3600
2025-03-10T20:09:01.360Z,1741637341.360 [Supervisor](DEBUG): Running supervisor.
2025-03-10T20:09:01.361Z,1741637341.361 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4922
2025-03-10T20:09:01.361Z,1741637341.361 [CommandExec](INFO): Initializing the command executive.
2025-03-10T20:09:01.363Z,1741637341.363 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4923
2025-03-10T20:09:01.365Z,1741637341.365 [controlThread ThreadHandler](INFO): Handler Thread ID is 4924
2025-03-10T20:09:01.366Z,1741637341.366 [controlThread](DEBUG): Initializing ControlThread
2025-03-10T20:09:01.367Z,1741637341.367 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-10T20:09:01.368Z,1741637341.368 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-10T20:09:01.369Z,1741637341.369 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-10T20:09:01.370Z,1741637341.370 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-10T20:09:01.372Z,1741637341.372 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-10T20:09:01.372Z,1741637341.372 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-10T20:09:01.377Z,1741637341.377 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-10T20:09:01.378Z,1741637341.378 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-10T20:09:01.378Z,1741637341.378 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-10T20:09:01.378Z,1741637341.378 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-10T20:09:01.379Z,1741637341.379 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-10T20:09:01.384Z,1741637341.384 [SBIT](INFO): Initialize SBIT Component.
2025-03-10T20:09:01.384Z,1741637341.384 [SBIT](IMPORTANT): git: 2025-03-10
2025-03-10T20:09:01.384Z,1741637341.384 [SBIT](INFO): git hash: ed984fb97dc40cbcfe09dec37ea56a9b8070d839
2025-03-10T20:09:01.385Z,1741637341.385 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-10T20:09:01.386Z,1741637341.386 [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-10T20:09:01.387Z,1741637341.387 [SBIT](INFO): Beginning SBIT in 53.000000 seconds.
2025-03-10T20:09:01.388Z,1741637341.388 [IBIT](INFO): Initialize IBIT Component.
2025-03-10T20:09:01.389Z,1741637341.389 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-10T20:09:01.389Z,1741637341.389 [logger ThreadHandler](INFO): Handler Thread ID is 4925
2025-03-10T20:09:01.399Z,1741637341.399 [CBIT](DEBUG): Initialized mux pins.
2025-03-10T20:09:01.400Z,1741637341.400 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-10T20:09:01.408Z,1741637341.408 [DAT ThreadHandler](INFO): Handler Thread ID is 4926
2025-03-10T20:09:01.409Z,1741637341.409 [DAT](INFO): Powering up
2025-03-10T20:09:01.409Z,1741637341.409 [DAT](DEBUG): Initializing DAT.
2025-03-10T20:09:01.413Z,1741637341.413 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4928
2025-03-10T20:09:01.415Z,1741637341.415 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-10T20:09:01.424Z,1741637341.424 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-10T20:09:01.424Z,1741637341.424 [CBIT](DEBUG): Initializing heartbeat.
2025-03-10T20:09:01.425Z,1741637341.425 [Onboard ThreadHandler](INFO): Handler Thread ID is 4929
2025-03-10T20:09:01.445Z,1741637341.445 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4930
2025-03-10T20:09:01.456Z,1741637341.456 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4931
2025-03-10T20:09:01.457Z,1741637341.457 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-10T20:09:01.461Z,1741637341.461 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4933
2025-03-10T20:09:01.465Z,1741637341.465 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4935
2025-03-10T20:09:01.468Z,1741637341.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-10T20:09:01.468Z,1741637341.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-10T20:09:01.468Z,1741637341.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-10T20:09:01.468Z,1741637341.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-10T20:09:01.468Z,1741637341.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-10T20:09:01.469Z,1741637341.469 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-10T20:09:01.469Z,1741637341.469 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-10T20:09:01.469Z,1741637341.469 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-10T20:09:01.484Z,1741637341.484 [WetLabsBB2FL](INFO): Powering up
2025-03-10T20:09:01.496Z,1741637341.496 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-10T20:09:01.535Z,1741637341.535 [CBIT](DEBUG): Backplane powered.
2025-03-10T20:09:01.536Z,1741637341.536 [GFScanner](DEBUG): Initializing GFScanner
2025-03-10T20:09:01.536Z,1741637341.536 [GFScanner](DEBUG): Deactivating GF circuits.
2025-03-10T20:09:01.541Z,1741637341.541 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-10T20:09:01.541Z,1741637341.541 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-10T20:09:01.541Z,1741637341.541 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-10T20:09:01.543Z,1741637341.543 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-10T20:09:01.596Z,1741637341.596 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-03-10T20:09:01.616Z,1741637341.616 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-10T20:09:01.638Z,1741637341.638 [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-10T20:09:01.638Z,1741637341.638 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-10T20:09:01.639Z,1741637341.639 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-10T20:09:01.664Z,1741637341.664 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-10T20:09:01.848Z,1741637341.848 [Radio_Surface](INFO): Powering up
2025-03-10T20:09:02.008Z,1741637342.008 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-03-10T20:09:02.014Z,1741637342.014 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-10T20:09:02.015Z,1741637342.015 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-10T20:09:02.018Z,1741637342.018 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-10T20:09:02.067Z,1741637342.067 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-10T20:09:02.070Z,1741637342.070 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-10T20:09:02.092Z,1741637342.092 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-10T20:09:02.112Z,1741637342.112 [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-10T20:09:02.117Z,1741637342.117 [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-10T20:09:02.149Z,1741637342.149 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-10T20:09:02.214Z,1741637342.214 [Power24vConverter](INFO): Powering up.
2025-03-10T20:09:02.215Z,1741637342.215 [Sonardyne_Nano](INFO): Initializing.
2025-03-10T20:09:02.232Z,1741637342.232 [CANONSampler](INFO): Powering down
2025-03-10T20:09:02.277Z,1741637342.277 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-10T20:09:02.284Z,1741637342.284 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-10T20:09:02.285Z,1741637342.285 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-10T20:09:02.292Z,1741637342.292 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-10T20:09:02.293Z,1741637342.293 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-10T20:09:02.300Z,1741637342.300 [MassServo](DEBUG): Initializing MassServo.
2025-03-10T20:09:02.301Z,1741637342.301 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-10T20:09:02.308Z,1741637342.308 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-10T20:09:02.309Z,1741637342.309 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-10T20:09:02.316Z,1741637342.316 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-10T20:09:02.566Z,1741637342.566 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-03-10T20:09:02.566Z,1741637342.566 [DropWeight] Hardware Fault, FailCount= 1
2025-03-10T20:09:02.566Z,1741637342.566 [DropWeight](ERROR): Hardware Fault
2025-03-10T20:09:02.591Z,1741637342.591 [CommandExec](FAULT): Scheduling is paused
2025-03-10T20:09:02.600Z,1741637342.600 [CBIT](INFO): Critical error at 20250310T200902
2025-03-10T20:09:02.600Z,1741637342.600 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-10T20:09:02.603Z,1741637342.603 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-03-10T20:09:02.603Z,1741637342.603 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-03-10T20:09:02.990Z,1741637342.990 [CBIT](INFO): Critical error at 20250310T200902
2025-03-10T20:09:03.572Z,1741637343.572 [WetLabsBB2FL](INFO): Powering down
2025-03-10T20:09:04.146Z,1741637344.146 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-10T20:09:05.432Z,1741637345.432 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2025-03-10T20:09:13.000Z,1741637353.000 [DAT](INFO): DAT read: 

2025-03-10T20:09:13.001Z,1741637353.001 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-03-10T20:09:14.513Z,1741637354.513 [DAT](INFO): DAT read: MF Frequency Band

2025-03-10T20:09:14.514Z,1741637354.514 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-03-10T20:09:14.514Z,1741637354.514 [DAT](INFO): DAT read: Mar 10 2025 20:09:09 

2025-03-10T20:09:15.523Z,1741637355.523 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-03-10T20:09:15.524Z,1741637355.524 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-03-10T20:09:15.524Z,1741637355.524 [DAT](INFO): commRate: 1200
2025-03-10T20:09:17.588Z,1741637357.588 [DAT](INFO): entering command mode
2025-03-10T20:09:17.788Z,1741637357.788 [DAT](INFO): DAT read: 

2025-03-10T20:09:17.789Z,1741637357.789 [DAT](INFO): DAT read: user:>

2025-03-10T20:09:17.789Z,1741637357.789 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:18.040Z,1741637358.040 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:18.292Z,1741637358.292 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:18.544Z,1741637358.544 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:18.796Z,1741637358.796 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:19.052Z,1741637359.052 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:19.304Z,1741637359.304 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:19.556Z,1741637359.556 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:19.808Z,1741637359.808 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:20.060Z,1741637360.060 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:20.312Z,1741637360.312 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:20.564Z,1741637360.564 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:20.816Z,1741637360.816 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:21.068Z,1741637361.068 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:21.320Z,1741637361.320 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:21.572Z,1741637361.572 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:21.824Z,1741637361.824 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:22.076Z,1741637362.076 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:22.328Z,1741637362.328 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:22.580Z,1741637362.580 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:22.832Z,1741637362.832 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:23.084Z,1741637363.084 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:23.336Z,1741637363.336 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:23.588Z,1741637363.588 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:23.840Z,1741637363.840 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:24.092Z,1741637364.092 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:24.349Z,1741637364.349 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:24.600Z,1741637364.600 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:24.852Z,1741637364.852 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:25.104Z,1741637365.104 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:25.356Z,1741637365.356 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:25.608Z,1741637365.608 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:25.860Z,1741637365.860 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:26.112Z,1741637366.112 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:26.364Z,1741637366.364 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:26.616Z,1741637366.616 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:26.868Z,1741637366.868 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:27.120Z,1741637367.120 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:27.372Z,1741637367.372 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:27.624Z,1741637367.624 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:27.876Z,1741637367.876 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:28.128Z,1741637368.128 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:28.380Z,1741637368.380 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:28.632Z,1741637368.632 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:28.884Z,1741637368.884 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:29.136Z,1741637369.136 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:29.388Z,1741637369.388 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:29.640Z,1741637369.640 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:29.892Z,1741637369.892 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:30.009Z,1741637370.009 [NAL9602](INFO): Powering up NAL9602
2025-03-10T20:09:30.144Z,1741637370.144 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:30.396Z,1741637370.396 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:30.648Z,1741637370.648 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:30.900Z,1741637370.900 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:31.152Z,1741637371.152 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:31.404Z,1741637371.404 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:31.656Z,1741637371.656 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:31.908Z,1741637371.908 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:32.160Z,1741637372.160 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:32.412Z,1741637372.412 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:32.664Z,1741637372.664 [DAT](DEBUG): checking for command mode acknowledgment
2025-03-10T20:09:32.664Z,1741637372.664 [DAT](FAULT): failed to enter command mode
2025-03-10T20:09:32.968Z,1741637372.968 [DAT](INFO): entering command mode
2025-03-10T20:09:33.168Z,1741637373.168 [DAT](INFO): DAT read: user:1>

2025-03-10T20:09:33.169Z,1741637373.169 [DAT](INFO): DAT read: Command '+++' not found

2025-03-10T20:09:33.170Z,1741637373.170 [DAT](INFO): DAT read: Error

2025-03-10T20:09:33.170Z,1741637373.170 [DAT](INFO): setting verbose to 3
2025-03-10T20:09:33.420Z,1741637373.420 [DAT](INFO): DAT read: user:2>

2025-03-10T20:09:33.421Z,1741637373.421 [DAT](INFO): DAT read: Verbose         | 3           

2025-03-10T20:09:33.422Z,1741637373.422 [DAT](INFO): set verbose to 3
2025-03-10T20:09:33.422Z,1741637373.422 [DAT](INFO): setting DatVerbose to 27440
2025-03-10T20:09:33.672Z,1741637373.672 [DAT](INFO): DAT read: user:3>

2025-03-10T20:09:33.673Z,1741637373.673 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-03-10T20:09:33.673Z,1741637373.673 [DAT](INFO): set DatVerbose to 27440
2025-03-10T20:09:33.674Z,1741637373.674 [DAT](INFO): setting transmit power to 8
2025-03-10T20:09:33.924Z,1741637373.924 [DAT](INFO): DAT read: user:4>

2025-03-10T20:09:33.925Z,1741637373.925 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-03-10T20:09:33.926Z,1741637373.926 [DAT](INFO): set transmit power to 8
2025-03-10T20:09:33.926Z,1741637373.926 [DAT](INFO): setting local address to 8
2025-03-10T20:09:34.176Z,1741637374.176 [DAT](INFO): DAT read: user:5>

2025-03-10T20:09:34.177Z,1741637374.177 [DAT](INFO): DAT read: LocalAddr       | 8           

2025-03-10T20:09:34.178Z,1741637374.178 [DAT](INFO): set local address to 8
2025-03-10T20:09:34.179Z,1741637374.179 [DAT](INFO): Setting time to: 20:9:34 And date to:3/10/2025
2025-03-10T20:09:34.428Z,1741637374.428 [DAT](INFO): DAT read: user:6>

2025-03-10T20:09:34.429Z,1741637374.429 [DAT](INFO): DAT read: Mon Mar 10, 2025  20:09:34  

2025-03-10T20:09:34.429Z,1741637374.429 [DAT](INFO): Local DAT time set to Mon Mar 10, 2025  20:09:34  

2025-03-10T20:09:40.917Z,1741637380.917 [NAL9602](INFO): NAL9602 initialized
2025-03-10T20:09:55.107Z,1741637395.107 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-10T20:09:55.124Z,1741637395.124 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T20:09:59.596Z,1741637399.596 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002443
CHAN A1 (24V): 0.000145
CHAN A2 (12V): -0.002244
CHAN A3 (5V): -0.001372
CHAN B0 (3.3V): -0.000333
CHAN B1 (3.15aV): -0.000196
CHAN B2 (3.15bV): -0.000172
CHAN B3 (GND): 0.000023
OPEN: -0.000230
Full Scale: +/- 1 mA
2025-03-10T20:10:08.155Z,1741637408.155 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.795477
2025-03-10T20:10:23.120Z,1741637423.120 [CommandExec](IMPORTANT): got command ibit
2025-03-10T20:10:23.390Z,1741637423.390 [IBIT](FAULT): Cannot run IBIT while SBIT is in progress.
2025-03-10T20:10:23.882Z,1741637423.882 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-03-10T20:10:23.882Z,1741637423.882 [IBIT](IMPORTANT): buoyancyNeutral: 355.541351 cc
2025-03-10T20:10:23.883Z,1741637423.883 [IBIT](IMPORTANT): massDefault: 0.847252 cm
2025-03-10T20:10:23.883Z,1741637423.883 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-03-10T20:10:23.883Z,1741637423.883 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-03-10T20:10:23.883Z,1741637423.883 [IBIT](IMPORTANT): IBIT FAILED
2025-03-10T20:10:48.835Z,1741637448.835 [SBIT](CRITICAL): SBIT FAILED
2025-03-10T20:10:48.835Z,1741637448.835 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-03-10T20:10:48.836Z,1741637448.836 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;

2025-03-10T20:10:48.836Z,1741637448.836 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;

2025-03-10T20:10:48.836Z,1741637448.836 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2025-03-10T20:10:48.836Z,1741637448.836 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-03-10T20:10:48.837Z,1741637448.837 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-03-10T20:10:48.837Z,1741637448.837 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2025-03-10T20:10:48.837Z,1741637448.837 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=355.541360 cubic_centimeter;

2025-03-10T20:10:48.837Z,1741637448.837 [SBIT](IMPORTANT): VerticalControl.massDefault=8.472520 millimeter;

2025-03-10T20:10:48.839Z,1741637448.839 [CBIT](INFO): Critical error at 20250310T201048
2025-03-10T20:10:49.246Z,1741637449.246 [MissionManager](IMPORTANT): Started mission Startup
2025-03-10T20:10:49.246Z,1741637449.246 [Startup] Running Loop=1
2025-03-10T20:10:49.246Z,1741637449.246 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-10T20:10:49.246Z,1741637449.246 [Startup:A.GoToSurface] Running Loop=1
2025-03-10T20:10:49.246Z,1741637449.246 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-10T20:10:49.247Z,1741637449.247 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-10T20:10:49.248Z,1741637449.248 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-10T20:10:49.248Z,1741637449.248 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-10T20:10:49.248Z,1741637449.248 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-10T20:10:49.249Z,1741637449.249 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-10T20:10:49.249Z,1741637449.249 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-10T20:10:49.251Z,1741637449.251 [Startup:StartupSatComms] Running Loop=1
2025-03-10T20:10:49.251Z,1741637449.251 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-10T20:10:49.251Z,1741637449.251 [Startup:StartupSatComms:A] Running Loop=1
2025-03-10T20:10:49.653Z,1741637449.653 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-10T20:10:55.750Z,1741637455.750 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-10T20:11:22.350Z,1741637482.350 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-03-10T20:11:30.716Z,1741637490.716 [CommandExec](IMPORTANT): got command ibit
2025-03-10T20:11:30.852Z,1741637490.852 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-03-10T20:11:30.852Z,1741637490.852 [IBIT](IMPORTANT): Beginning control surface checks.
2025-03-10T20:11:30.856Z,1741637490.856 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T20:11:35.500Z,1741637495.500 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002912
CHAN A1 (24V): 0.000337
CHAN A2 (12V): -0.002209
CHAN A3 (5V): -0.001228
CHAN B0 (3.3V): -0.000383
CHAN B1 (3.15aV): -0.000202
CHAN B2 (3.15bV): -0.000931
CHAN B3 (GND): -0.000116
OPEN: -0.000442
Full Scale: +/- 1 mA
2025-03-10T20:11:44.485Z,1741637504.485 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003987
2025-03-10T20:11:45.954Z,1741637505.954 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.795477
2025-03-10T20:11:45.954Z,1741637505.954 [IBIT](FAULT): Control surface position failure.
2025-03-10T20:12:01.599Z,1741637521.599 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-10T20:12:01.599Z,1741637521.599 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:12:01.634Z,1741637521.634 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:12:01.963Z,1741637521.963 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:12:01.963Z,1741637521.963 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-03-10T20:13:11.946Z,1741637591.946 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-10T20:13:19.910Z,1741637599.910 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:13:21.587Z,1741637601.587 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-03-10T20:13:21.966Z,1741637601.966 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-03-10T20:13:22.357Z,1741637602.357 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-03-10T20:13:22.357Z,1741637602.357 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-03-10T20:13:22.358Z,1741637602.358 [IBIT](IMPORTANT): Pressure:9.414983 PSI
2025-03-10T20:13:22.358Z,1741637602.358 [IBIT](IMPORTANT): Humidity:17.244537 %
2025-03-10T20:13:22.776Z,1741637602.776 [IBIT](IMPORTANT): Vehicle Pitch:-0.634767 degrees
2025-03-10T20:13:22.777Z,1741637602.777 [IBIT](IMPORTANT): Vehicle  Roll:-5.739594 degrees
2025-03-10T20:13:22.777Z,1741637602.777 [IBIT](IMPORTANT): Vehicle  Heading:108.617638 degrees
2025-03-10T20:13:23.167Z,1741637603.167 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-03-10T20:13:23.183Z,1741637603.183 [IBIT](IMPORTANT): buoyancyNeutral: 355.541351 cc
2025-03-10T20:13:23.184Z,1741637603.184 [IBIT](IMPORTANT): massDefault: 0.847252 cm
2025-03-10T20:13:23.184Z,1741637603.184 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-03-10T20:13:23.184Z,1741637603.184 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-03-10T20:13:23.185Z,1741637603.185 [IBIT](IMPORTANT): IBIT FAILED
2025-03-10T20:13:23.572Z,1741637603.572 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-10T20:10:49.3Z
2025-03-10T20:13:23.572Z,1741637603.572 [Startup:StartupSatComms:A] Stopped
2025-03-10T20:13:23.572Z,1741637603.572 [Startup:StartupSatComms:B] Running Loop=1
2025-03-10T20:13:23.980Z,1741637603.980 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-10T20:13:29.499Z,1741637609.499 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2025-03-10T20:13:29.507Z,1741637609.507 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2025-03-10T20:13:29.801Z,1741637609.801 [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,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2025-03-10T20:13:32.476Z,1741637612.476 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T195846/Courier0007.lzma
2025-03-10T20:13:33.477Z,1741637613.477 [DataOverHttps](INFO): Moved sent file to Logs/20250310T195846/Courier0007.lzma.bak
2025-03-10T20:13:33.478Z,1741637613.478 [DataOverHttps](INFO): SBD MOMSN=24150496
2025-03-10T20:13:39.321Z,1741637619.321 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:13:49.412Z,1741637629.412 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250310T200850/Courier0000.lzma
2025-03-10T20:13:50.413Z,1741637630.413 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Courier0000.lzma.bak
2025-03-10T20:13:50.414Z,1741637630.414 [DataOverHttps](INFO): SBD MOMSN=24150498
2025-03-10T20:13:57.511Z,1741637637.511 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:14:06.159Z,1741637646.159 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20250310T195846/Express0008.lzma
2025-03-10T20:14:07.161Z,1741637647.161 [DataOverHttps](INFO): Moved sent file to Logs/20250310T195846/Express0008.lzma.bak
2025-03-10T20:14:07.161Z,1741637647.161 [DataOverHttps](INFO): SBD MOMSN=24150504
2025-03-10T20:14:17.365Z,1741637657.365 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:14:23.583Z,1741637663.583 [DataOverHttps](INFO): Sending 1490 bytes from file Logs/20250310T200850/Express0001.lzma
2025-03-10T20:14:23.782Z,1741637663.782 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-10T20:13:23.6Z
2025-03-10T20:14:23.782Z,1741637663.782 [Startup:StartupSatComms:B] Stopped
2025-03-10T20:14:23.782Z,1741637663.782 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-10T20:14:23.783Z,1741637663.783 [Startup:StartupSatComms] Stopped
2025-03-10T20:14:23.783Z,1741637663.783 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-10T20:14:23.784Z,1741637663.784 [Startup](INFO): Completed Startup
2025-03-10T20:14:23.784Z,1741637663.784 [MissionManager](INFO): Startup is completed.
2025-03-10T20:14:23.784Z,1741637663.784 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-10T20:14:23.784Z,1741637663.784 [Startup] Stopped
2025-03-10T20:14:23.784Z,1741637663.784 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-10T20:14:23.784Z,1741637663.784 [Startup:A.GoToSurface] Stopped
2025-03-10T20:14:23.785Z,1741637663.784 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-10T20:14:24.195Z,1741637664.195 [MissionManager](IMPORTANT): Started mission Default
2025-03-10T20:14:24.195Z,1741637664.195 [Default] Running Loop=1
2025-03-10T20:14:24.195Z,1741637664.195 [Default](DEBUG): Aggregate::initialize Default
2025-03-10T20:14:24.195Z,1741637664.195 [Default:B.GoToSurface] Running Loop=1
2025-03-10T20:14:24.196Z,1741637664.196 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-10T20:14:24.196Z,1741637664.196 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-10T20:14:24.196Z,1741637664.196 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-10T20:14:24.196Z,1741637664.196 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-10T20:14:24.197Z,1741637664.197 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-10T20:14:24.197Z,1741637664.197 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-10T20:14:24.197Z,1741637664.197 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-10T20:14:24.198Z,1741637664.198 [Default:A.Wait] Running Loop=1
2025-03-10T20:14:24.198Z,1741637664.198 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-10T20:14:24.581Z,1741637664.581 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Express0001.lzma.bak
2025-03-10T20:14:24.581Z,1741637664.581 [DataOverHttps](INFO): SBD MOMSN=24150507
2025-03-10T20:14:30.289Z,1741637670.289 [CommandExec](IMPORTANT): got command get platform_rudder_angle degree
2025-03-10T20:14:30.290Z,1741637670.290 [CommandExec](IMPORTANT): platform_rudder_angle 14.956034 arcdeg
2025-03-10T20:14:36.294Z,1741637676.294 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:14:37.559Z,1741637677.559 [Default:A.Wait](INFO): Done Waiting.
2025-03-10T20:14:37.559Z,1741637677.559 [Default:A.Wait] Stopped
2025-03-10T20:14:37.559Z,1741637677.559 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T20:14:37.950Z,1741637677.950 [Default:CheckIn] Running Loop=1
2025-03-10T20:14:37.951Z,1741637677.951 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T20:14:37.951Z,1741637677.951 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T20:14:38.362Z,1741637678.362 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-10T20:14:44.785Z,1741637684.785 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-10T20:14:56.496Z,1741637696.496 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:15:02.568Z,1741637702.568 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-03-10T20:15:02.568Z,1741637702.568 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:15:02.579Z,1741637702.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:15:03.003Z,1741637703.003 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:15:03.003Z,1741637703.003 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-03-10T20:15:15.887Z,1741637715.887 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:15:16.171Z,1741637716.171 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2025-03-10T20:15:30.120Z,1741637730.120 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-10T20:15:34.639Z,1741637734.639 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2025-03-10T20:15:35.284Z,1741637735.284 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:16:11.918Z,1741637771.918 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2025-03-10T20:16:14.476Z,1741637774.476 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5.
2025-03-10T20:16:14.479Z,1741637774.479 [BPC1](INFO): Received data from all battery sticks.
2025-03-10T20:16:33.836Z,1741637793.836 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2025-03-10T20:17:40.899Z,1741637860.899 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree
2025-03-10T20:17:40.903Z,1741637860.903 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2025-03-10T20:17:40.985Z,1741637860.985 [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,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2025-03-10T20:18:00.420Z,1741637880.420 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree
2025-03-10T20:18:03.584Z,1741637883.584 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-03-10T20:18:03.584Z,1741637883.584 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:18:03.637Z,1741637883.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:18:04.042Z,1741637884.042 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:18:04.042Z,1741637884.042 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-03-10T20:19:38.107Z,1741637978.107 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T20:14:37.0Z
2025-03-10T20:19:38.107Z,1741637978.107 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T20:19:38.107Z,1741637978.107 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T20:19:38.502Z,1741637978.502 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-10T20:19:42.125Z,1741637982.125 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T20:19:42.125Z,1741637982.125 [NAL9602] Data Fault, FailCount= 1
2025-03-10T20:19:42.125Z,1741637982.125 [NAL9602](ERROR): Data Fault
2025-03-10T20:19:42.191Z,1741637982.191 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T20:19:42.529Z,1741637982.529 [NAL9602](INFO): Powering down
2025-03-10T20:19:43.385Z,1741637983.385 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T20:19:43.385Z,1741637983.385 [NAL9602] No Fault, FailCount= 1
2025-03-10T20:19:45.520Z,1741637985.520 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250310T200850/Courier0004.lzma
2025-03-10T20:19:46.521Z,1741637986.521 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Courier0004.lzma.bak
2025-03-10T20:19:46.521Z,1741637986.521 [DataOverHttps](INFO): SBD MOMSN=24150570
2025-03-10T20:20:02.176Z,1741638002.176 [DataOverHttps](INFO): Sending 359 bytes from file Logs/20250310T200850/Express0005.lzma
2025-03-10T20:20:03.179Z,1741638003.179 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Express0005.lzma.bak
2025-03-10T20:20:03.179Z,1741638003.179 [DataOverHttps](INFO): SBD MOMSN=24150574
2025-03-10T20:20:04.760Z,1741638004.760 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T20:20:04.760Z,1741638004.760 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T20:20:04.760Z,1741638004.760 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T20:20:12.834Z,1741638012.834 [NAL9602](INFO): Powering up NAL9602
2025-03-10T20:20:23.741Z,1741638023.741 [NAL9602](INFO): NAL9602 initialized
2025-03-10T20:21:04.574Z,1741638064.574 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-03-10T20:21:04.574Z,1741638064.574 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:21:04.614Z,1741638064.614 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:21:04.963Z,1741638064.963 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:21:04.963Z,1741638064.963 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-03-10T20:24:02.736Z,1741638242.736 [CBIT](INFO): Clearing failed state for component DropWeight
2025-03-10T20:24:02.736Z,1741638242.736 [DropWeight] No Fault, FailCount= 1
2025-03-10T20:24:05.561Z,1741638245.561 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-03-10T20:24:05.561Z,1741638245.561 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:24:05.572Z,1741638245.572 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:24:05.972Z,1741638245.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:24:05.972Z,1741638245.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-03-10T20:25:05.337Z,1741638305.337 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T20:25:05.337Z,1741638305.337 [Default:CheckIn:C.Wait] Stopped
2025-03-10T20:25:05.337Z,1741638305.337 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T20:25:05.337Z,1741638305.337 [Default:CheckIn:D] Running Loop=1
2025-03-10T20:25:05.762Z,1741638305.762 [Default:CheckIn:D] Stopped
2025-03-10T20:25:05.762Z,1741638305.762 [Default:CheckIn:E] Running Loop=1
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.692776 min 
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn:E] Stopped
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn] Stopped
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn](INFO): Running loop #2
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn] Running Loop=2
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T20:25:06.161Z,1741638306.161 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T20:27:06.564Z,1741638426.564 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-03-10T20:27:06.564Z,1741638426.564 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:27:06.575Z,1741638426.575 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:27:06.952Z,1741638426.952 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:27:06.952Z,1741638426.952 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-03-10T20:30:06.342Z,1741638606.342 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T20:25:06.2Z
2025-03-10T20:30:06.342Z,1741638606.342 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T20:30:06.342Z,1741638606.342 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T20:30:07.585Z,1741638607.585 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-03-10T20:30:07.585Z,1741638607.585 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:30:07.598Z,1741638607.598 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:30:07.967Z,1741638607.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:30:07.967Z,1741638607.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-03-10T20:30:13.459Z,1741638613.459 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T200850/Courier0007.lzma
2025-03-10T20:30:14.462Z,1741638614.462 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Courier0007.lzma.bak
2025-03-10T20:30:14.462Z,1741638614.462 [DataOverHttps](INFO): SBD MOMSN=24150609
2025-03-10T20:30:24.902Z,1741638624.902 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T20:30:24.902Z,1741638624.902 [NAL9602] Data Fault, FailCount= 2
2025-03-10T20:30:24.902Z,1741638624.902 [NAL9602](ERROR): Data Fault
2025-03-10T20:30:24.920Z,1741638624.920 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T20:30:25.308Z,1741638625.308 [NAL9602](INFO): Powering down
2025-03-10T20:30:26.158Z,1741638626.158 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T20:30:26.158Z,1741638626.158 [NAL9602] No Fault, FailCount= 2
2025-03-10T20:30:30.100Z,1741638630.100 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250310T200850/Express0008.lzma
2025-03-10T20:30:31.101Z,1741638631.101 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Express0008.lzma.bak
2025-03-10T20:30:31.102Z,1741638631.102 [DataOverHttps](INFO): SBD MOMSN=24150611
2025-03-10T20:30:32.588Z,1741638632.588 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T20:30:32.588Z,1741638632.588 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T20:30:32.588Z,1741638632.588 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T20:30:55.597Z,1741638655.597 [NAL9602](INFO): Powering up NAL9602
2025-03-10T20:31:06.505Z,1741638666.505 [NAL9602](INFO): NAL9602 initialized
2025-03-10T20:33:08.533Z,1741638788.533 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-03-10T20:33:08.533Z,1741638788.533 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:33:08.557Z,1741638788.557 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:33:08.972Z,1741638788.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:33:08.972Z,1741638788.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-03-10T20:35:33.147Z,1741638933.147 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T20:35:33.147Z,1741638933.147 [Default:CheckIn:C.Wait] Stopped
2025-03-10T20:35:33.147Z,1741638933.147 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T20:35:33.160Z,1741638933.160 [Default:CheckIn:D] Running Loop=1
2025-03-10T20:35:33.582Z,1741638933.582 [Default:CheckIn:D] Stopped
2025-03-10T20:35:33.582Z,1741638933.582 [Default:CheckIn:E] Running Loop=1
2025-03-10T20:35:33.994Z,1741638933.994 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  21.156451 min 
2025-03-10T20:35:33.994Z,1741638933.994 [Default:CheckIn:E] Stopped
2025-03-10T20:35:33.994Z,1741638933.994 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T20:35:33.995Z,1741638933.995 [Default:CheckIn] Stopped
2025-03-10T20:35:33.995Z,1741638933.995 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T20:35:33.995Z,1741638933.995 [Default:CheckIn](INFO): Running loop #3
2025-03-10T20:35:33.995Z,1741638933.995 [Default:CheckIn] Running Loop=3
2025-03-10T20:35:33.995Z,1741638933.995 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T20:35:33.995Z,1741638933.995 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T20:36:09.524Z,1741638969.524 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-03-10T20:36:09.524Z,1741638969.524 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:36:09.580Z,1741638969.580 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:36:09.946Z,1741638969.946 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:36:09.946Z,1741638969.946 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-03-10T20:39:10.539Z,1741639150.539 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-03-10T20:39:10.539Z,1741639150.539 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:39:10.550Z,1741639150.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:39:10.943Z,1741639150.943 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:39:10.943Z,1741639150.943 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-03-10T20:40:34.150Z,1741639234.150 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T20:35:33.0Z
2025-03-10T20:40:34.150Z,1741639234.150 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T20:40:34.150Z,1741639234.150 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T20:40:41.815Z,1741639241.815 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T200850/Courier0010.lzma
2025-03-10T20:40:42.817Z,1741639242.817 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Courier0010.lzma.bak
2025-03-10T20:40:42.817Z,1741639242.817 [DataOverHttps](INFO): SBD MOMSN=24150676
2025-03-10T20:41:03.980Z,1741639263.980 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250310T200850/Express0011.lzma
2025-03-10T20:41:04.981Z,1741639264.981 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Express0011.lzma.bak
2025-03-10T20:41:04.982Z,1741639264.982 [DataOverHttps](INFO): SBD MOMSN=24150679
2025-03-10T20:41:06.514Z,1741639266.514 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T20:41:06.514Z,1741639266.514 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T20:41:06.514Z,1741639266.514 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T20:41:09.321Z,1741639269.321 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T20:41:09.321Z,1741639269.321 [NAL9602] Data Fault, FailCount= 3
2025-03-10T20:41:09.321Z,1741639269.321 [NAL9602](ERROR): Data Fault
2025-03-10T20:41:09.403Z,1741639269.403 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T20:41:09.713Z,1741639269.713 [NAL9602](INFO): Powering down
2025-03-10T20:41:10.582Z,1741639270.582 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T20:41:10.582Z,1741639270.582 [NAL9602] No Fault, FailCount= 3
2025-03-10T20:41:40.017Z,1741639300.017 [NAL9602](INFO): Powering up NAL9602
2025-03-10T20:41:50.925Z,1741639310.925 [NAL9602](INFO): NAL9602 initialized
2025-03-10T20:42:11.537Z,1741639331.537 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-03-10T20:42:11.537Z,1741639331.537 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:42:11.586Z,1741639331.586 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:42:11.982Z,1741639331.982 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:42:11.982Z,1741639331.982 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-03-10T20:45:12.536Z,1741639512.536 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-03-10T20:45:12.536Z,1741639512.536 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:45:12.580Z,1741639512.580 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:45:12.968Z,1741639512.968 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:45:12.968Z,1741639512.968 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-03-10T20:46:07.098Z,1741639567.098 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T20:46:07.098Z,1741639567.098 [Default:CheckIn:C.Wait] Stopped
2025-03-10T20:46:07.098Z,1741639567.098 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T20:46:07.098Z,1741639567.098 [Default:CheckIn:D] Running Loop=1
2025-03-10T20:46:07.488Z,1741639567.488 [Default:CheckIn:D] Stopped
2025-03-10T20:46:07.488Z,1741639567.488 [Default:CheckIn:E] Running Loop=1
2025-03-10T20:46:07.882Z,1741639567.882 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  31.721549 min 
2025-03-10T20:46:07.883Z,1741639567.883 [Default:CheckIn:E] Stopped
2025-03-10T20:46:07.883Z,1741639567.883 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T20:46:07.883Z,1741639567.883 [Default:CheckIn] Stopped
2025-03-10T20:46:07.883Z,1741639567.883 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T20:46:07.883Z,1741639567.883 [Default:CheckIn](INFO): Running loop #4
2025-03-10T20:46:07.883Z,1741639567.883 [Default:CheckIn] Running Loop=4
2025-03-10T20:46:07.908Z,1741639567.908 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T20:46:07.908Z,1741639567.908 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T20:48:13.520Z,1741639693.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-03-10T20:48:13.520Z,1741639693.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:48:13.532Z,1741639693.532 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:48:13.977Z,1741639693.977 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:48:13.977Z,1741639693.977 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-03-10T20:51:08.117Z,1741639868.117 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T20:46:07.9Z
2025-03-10T20:51:08.117Z,1741639868.117 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T20:51:08.117Z,1741639868.117 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T20:51:14.577Z,1741639874.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-03-10T20:51:14.577Z,1741639874.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:51:14.597Z,1741639874.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:51:14.967Z,1741639874.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:51:14.968Z,1741639874.968 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-03-10T20:51:15.715Z,1741639875.715 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T200850/Courier0013.lzma
2025-03-10T20:51:16.717Z,1741639876.717 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Courier0013.lzma.bak
2025-03-10T20:51:16.718Z,1741639876.718 [DataOverHttps](INFO): SBD MOMSN=24150701
2025-03-10T20:51:32.280Z,1741639892.280 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250310T200850/Express0014.lzma
2025-03-10T20:51:33.282Z,1741639893.282 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Express0014.lzma.bak
2025-03-10T20:51:33.282Z,1741639893.282 [DataOverHttps](INFO): SBD MOMSN=24150703
2025-03-10T20:51:34.782Z,1741639894.782 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T20:51:34.782Z,1741639894.782 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T20:51:34.782Z,1741639894.782 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T20:51:52.918Z,1741639912.918 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T20:51:52.918Z,1741639912.918 [NAL9602] Data Fault, FailCount= 4
2025-03-10T20:51:52.918Z,1741639912.918 [NAL9602](ERROR): Data Fault
2025-03-10T20:51:52.936Z,1741639912.936 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T20:51:53.321Z,1741639913.321 [NAL9602](INFO): Powering down
2025-03-10T20:51:54.159Z,1741639914.159 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T20:51:54.160Z,1741639914.160 [NAL9602] No Fault, FailCount= 4
2025-03-10T20:52:23.625Z,1741639943.625 [NAL9602](INFO): Powering up NAL9602
2025-03-10T20:52:34.534Z,1741639954.534 [NAL9602](INFO): NAL9602 initialized
2025-03-10T20:54:04.225Z,1741640044.225 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-03-10T20:54:04.225Z,1741640044.225 [DropWeight] Hardware Fault, FailCount= 1
2025-03-10T20:54:04.225Z,1741640044.225 [DropWeight](ERROR): Hardware Fault
2025-03-10T20:54:04.299Z,1741640044.299 [CBIT](INFO): Critical error at 20250310T205404
2025-03-10T20:54:04.302Z,1741640044.302 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-03-10T20:54:04.302Z,1741640044.302 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-03-10T20:54:04.649Z,1741640044.649 [CBIT](INFO): Critical error at 20250310T205404
2025-03-10T20:54:15.548Z,1741640055.548 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-03-10T20:54:15.548Z,1741640055.548 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:54:15.560Z,1741640055.560 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:54:15.971Z,1741640055.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:54:15.971Z,1741640055.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-03-10T20:56:35.329Z,1741640195.329 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T20:56:35.329Z,1741640195.329 [Default:CheckIn:C.Wait] Stopped
2025-03-10T20:56:35.329Z,1741640195.329 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T20:56:35.329Z,1741640195.329 [Default:CheckIn:D] Running Loop=1
2025-03-10T20:56:35.742Z,1741640195.742 [Default:CheckIn:D] Stopped
2025-03-10T20:56:35.742Z,1741640195.742 [Default:CheckIn:E] Running Loop=1
2025-03-10T20:56:36.151Z,1741640196.151 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  42.192444 min 
2025-03-10T20:56:36.151Z,1741640196.151 [Default:CheckIn:E] Stopped
2025-03-10T20:56:36.151Z,1741640196.151 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T20:56:36.151Z,1741640196.151 [Default:CheckIn] Stopped
2025-03-10T20:56:36.152Z,1741640196.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T20:56:36.152Z,1741640196.152 [Default:CheckIn](INFO): Running loop #5
2025-03-10T20:56:36.152Z,1741640196.152 [Default:CheckIn] Running Loop=5
2025-03-10T20:56:36.152Z,1741640196.152 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T20:56:36.152Z,1741640196.152 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T20:57:16.547Z,1741640236.547 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-03-10T20:57:16.547Z,1741640236.547 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:57:16.559Z,1741640236.559 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:57:16.972Z,1741640236.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:57:16.972Z,1741640236.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-03-10T20:58:40.597Z,1741640320.597 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2025-03-10T20:59:01.030Z,1741640341.030 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2025-03-10T20:59:18.714Z,1741640358.714 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2025-03-10T20:59:22.785Z,1741640362.785 [CommandExec](IMPORTANT): got command maintain clear
2025-03-10T20:59:23.061Z,1741640363.061 [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-10T20:59:34.977Z,1741640374.977 [CommandExec](IMPORTANT): got command ibit
2025-03-10T20:59:35.281Z,1741640375.281 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-03-10T20:59:35.281Z,1741640375.281 [IBIT](IMPORTANT): Beginning control surface checks.
2025-03-10T20:59:35.288Z,1741640375.288 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T20:59:39.621Z,1741640379.621 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002709
CHAN A1 (24V): 0.000559
CHAN A2 (12V): -0.002319
CHAN A3 (5V): -0.001536
CHAN B0 (3.3V): -0.000284
CHAN B1 (3.15aV): 0.000263
CHAN B2 (3.15bV): 0.000116
CHAN B3 (GND): 0.000412
OPEN: -0.000573
Full Scale: +/- 1 mA
2025-03-10T20:59:50.618Z,1741640390.618 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.797676
2025-03-10T20:59:50.618Z,1741640390.618 [IBIT](FAULT): Control surface position failure.
2025-03-10T21:00:17.553Z,1741640417.553 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-03-10T21:00:17.553Z,1741640417.553 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T21:00:17.564Z,1741640417.564 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T21:00:17.969Z,1741640417.969 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T21:00:17.969Z,1741640417.969 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-03-10T21:00:25.977Z,1741640425.977 [CommandExec](IMPORTANT): got command ibit
2025-03-10T21:00:26.019Z,1741640426.019 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-03-10T21:00:26.019Z,1741640426.019 [IBIT](IMPORTANT): Beginning control surface checks.
2025-03-10T21:00:26.042Z,1741640426.042 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T21:00:30.528Z,1741640430.528 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002645
CHAN A1 (24V): 0.000225
CHAN A2 (12V): -0.002097
CHAN A3 (5V): -0.001554
CHAN B0 (3.3V): 0.000182
CHAN B1 (3.15aV): 0.000142
CHAN B2 (3.15bV): -0.000048
CHAN B3 (GND): 0.000382
OPEN: -0.000512
Full Scale: +/- 1 mA
2025-03-10T21:02:16.738Z,1741640536.738 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-03-10T21:02:17.147Z,1741640537.147 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.739000
2025-03-10T21:02:17.147Z,1741640537.147 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-03-10T21:02:17.152Z,1741640537.152 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-03-10T21:02:17.527Z,1741640537.527 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-03-10T21:02:17.532Z,1741640537.532 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-03-10T21:02:17.532Z,1741640537.532 [IBIT](IMPORTANT): Pressure:9.439943 PSI
2025-03-10T21:02:17.532Z,1741640537.532 [IBIT](IMPORTANT): Humidity:17.220119 %
2025-03-10T21:02:17.943Z,1741640537.943 [IBIT](IMPORTANT): Vehicle Pitch:-0.556616 degrees
2025-03-10T21:02:17.948Z,1741640537.948 [IBIT](IMPORTANT): Vehicle  Roll:-5.742357 degrees
2025-03-10T21:02:17.948Z,1741640537.948 [IBIT](IMPORTANT): Vehicle  Heading:108.317284 degrees
2025-03-10T21:02:18.361Z,1741640538.361 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-03-10T21:02:18.361Z,1741640538.361 [IBIT](IMPORTANT): buoyancyNeutral: 355.541351 cc
2025-03-10T21:02:18.361Z,1741640538.361 [IBIT](IMPORTANT): massDefault: 0.847252 cm
2025-03-10T21:02:18.362Z,1741640538.362 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-03-10T21:02:18.362Z,1741640538.362 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-03-10T21:02:18.362Z,1741640538.362 [IBIT](IMPORTANT): IBIT FAILED
2025-03-10T21:02:18.734Z,1741640538.734 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T20:56:36.2Z
2025-03-10T21:02:18.734Z,1741640538.734 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T21:02:18.734Z,1741640538.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T21:02:19.764Z,1741640539.764 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20250310T200850/Courier0016.lzma
2025-03-10T21:02:20.669Z,1741640540.669 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Courier0016.lzma.bak
2025-03-10T21:02:20.670Z,1741640540.670 [DataOverHttps](INFO): SBD MOMSN=24150822
2025-03-10T21:02:24.804Z,1741640544.804 [CommandExec](IMPORTANT): got command ibit
2025-03-10T21:02:24.965Z,1741640544.965 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-03-10T21:02:24.965Z,1741640544.965 [IBIT](IMPORTANT): Beginning control surface checks.
2025-03-10T21:02:24.969Z,1741640544.969 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T21:02:29.310Z,1741640549.310 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002809
CHAN A1 (24V): 0.000696
CHAN A2 (12V): -0.002139
CHAN A3 (5V): -0.001690
CHAN B0 (3.3V): -0.000448
CHAN B1 (3.15aV): 0.000340
CHAN B2 (3.15bV): 0.000272
CHAN B3 (GND): 0.000274
OPEN: -0.000307
Full Scale: +/- 1 mA
2025-03-10T21:02:35.685Z,1741640555.685 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T21:02:35.685Z,1741640555.685 [NAL9602] Data Fault, FailCount= 5
2025-03-10T21:02:35.685Z,1741640555.685 [NAL9602](ERROR): Data Fault
2025-03-10T21:02:35.892Z,1741640555.892 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T21:02:35.892Z,1741640555.892 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-03-10T21:02:36.093Z,1741640556.093 [NAL9602](INFO): Powering down
2025-03-10T21:02:36.242Z,1741640556.242 [CBIT](INFO): Critical error at 20250310T210235
2025-03-10T21:02:36.801Z,1741640556.801 [DataOverHttps](INFO): Sending 920 bytes from file Logs/20250310T200850/Express0017.lzma
2025-03-10T21:02:37.801Z,1741640557.801 [DataOverHttps](INFO): Moved sent file to Logs/20250310T200850/Express0017.lzma.bak
2025-03-10T21:02:37.802Z,1741640557.802 [DataOverHttps](INFO): SBD MOMSN=24150826
2025-03-10T21:03:18.421Z,1741640598.421 [CommandExec](IMPORTANT): got command restart application
2025-03-10T21:03:18.533Z,1741640598.533 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-03-10T21:03:18.533Z,1741640598.533 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T21:03:18.546Z,1741640598.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T21:03:18.953Z,1741640598.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T21:03:18.954Z,1741640598.954 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-03-10T21:03:19.424Z,1741640599.424 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:19.424Z,1741640599.424 [CommandExec](INFO): Uninitializing the command executive.
2025-03-10T21:03:19.424Z,1741640599.424 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-10T21:03:19.424Z,1741640599.424 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.476Z,1741640599.476 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-03-10T21:03:19.476Z,1741640599.476 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-10T21:03:19.476Z,1741640599.476 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.477Z,1741640599.477 [NavChartDb](INFO): Join timeout helper Thread ID is 5013
2025-03-10T21:03:19.540Z,1741640599.540 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:19.540Z,1741640599.540 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.548Z,1741640599.548 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-03-10T21:03:19.548Z,1741640599.548 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.548Z,1741640599.548 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5014
2025-03-10T21:03:19.692Z,1741640599.692 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:19.692Z,1741640599.692 [WetLabsBB2FL](INFO): Powering down
2025-03-10T21:03:19.693Z,1741640599.693 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.696Z,1741640599.696 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-10T21:03:19.696Z,1741640599.696 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.696Z,1741640599.696 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5015
2025-03-10T21:03:19.940Z,1741640599.940 [CTD_Seabird](INFO): Powering down
2025-03-10T21:03:19.952Z,1741640599.952 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:19.952Z,1741640599.952 [CTD_Seabird](INFO): Powering down
2025-03-10T21:03:19.964Z,1741640599.964 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.980Z,1741640599.980 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-10T21:03:19.980Z,1741640599.980 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:19.980Z,1741640599.980 [Radio_Surface](INFO): Join timeout helper Thread ID is 5016
2025-03-10T21:03:20.288Z,1741640600.288 [Radio_Surface](INFO): Powering down
2025-03-10T21:03:20.289Z,1741640600.289 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:20.290Z,1741640600.290 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:20.308Z,1741640600.308 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-10T21:03:20.308Z,1741640600.308 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:20.308Z,1741640600.308 [Onboard](INFO): Join timeout helper Thread ID is 5017
2025-03-10T21:03:24.160Z,1741640604.160 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:24.160Z,1741640604.160 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.168Z,1741640604.168 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-10T21:03:24.169Z,1741640604.169 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.169Z,1741640604.169 [DataOverHttps](INFO): Join timeout helper Thread ID is 5018
2025-03-10T21:03:24.212Z,1741640604.212 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:24.214Z,1741640604.214 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.216Z,1741640604.216 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-03-10T21:03:24.216Z,1741640604.216 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.217Z,1741640604.217 [DAT](INFO): Join timeout helper Thread ID is 5019
2025-03-10T21:03:24.364Z,1741640604.364 [DAT](INFO): Powering down
2025-03-10T21:03:24.436Z,1741640604.436 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:24.436Z,1741640604.436 [DAT](INFO): Powering down
2025-03-10T21:03:24.437Z,1741640604.437 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.452Z,1741640604.452 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-10T21:03:24.452Z,1741640604.452 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.453Z,1741640604.453 [logger](INFO): Join timeout helper Thread ID is 5020
2025-03-10T21:03:24.480Z,1741640604.480 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:24.480Z,1741640604.480 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.492Z,1741640604.492 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-10T21:03:24.492Z,1741640604.492 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.493Z,1741640604.493 [CommandLine](INFO): Join timeout helper Thread ID is 5021
2025-03-10T21:03:24.588Z,1741640604.588 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:24.588Z,1741640604.588 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.592Z,1741640604.592 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-10T21:03:24.592Z,1741640604.592 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.593Z,1741640604.593 [CommandExec](INFO): Join timeout helper Thread ID is 5022
2025-03-10T21:03:24.597Z,1741640604.597 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-10T21:03:24.597Z,1741640604.597 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:24.598Z,1741640604.598 [controlThread](INFO): Join timeout helper Thread ID is 5023
2025-03-10T21:03:24.960Z,1741640604.960 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T21:03:24.960Z,1741640604.960 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-10T21:03:24.960Z,1741640604.960 [AHRS_M2](INFO): Powering down
2025-03-10T21:03:25.032Z,1741640605.032 [NAL9602](INFO): Powering down
2025-03-10T21:03:25.033Z,1741640605.033 [Sonardyne_Nano](INFO): Powering down
2025-03-10T21:03:25.153Z,1741640605.153 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-10T21:03:25.154Z,1741640605.154 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-10T21:03:25.154Z,1741640605.154 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-10T21:03:25.155Z,1741640605.155 [MissionManager](INFO): Uninitializing Mission Default
2025-03-10T21:03:25.155Z,1741640605.155 [Default] Stopped
2025-03-10T21:03:25.155Z,1741640605.155 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-10T21:03:25.155Z,1741640605.155 [Default:B.GoToSurface] Stopped
2025-03-10T21:03:25.155Z,1741640605.155 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-10T21:03:25.155Z,1741640605.155 [Default:CheckIn] Stopped
2025-03-10T21:03:25.155Z,1741640605.155 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T21:03:25.155Z,1741640605.155 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T21:03:25.159Z,1741640605.159 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-10T21:03:25.159Z,1741640605.159 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-10T21:03:25.159Z,1741640605.159 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-10T21:03:25.160Z,1741640605.160 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-10T21:03:25.160Z,1741640605.160 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-10T21:03:25.160Z,1741640605.160 [BuoyancyServo](INFO): Powering down
2025-03-10T21:03:25.172Z,1741640605.172 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-10T21:03:25.172Z,1741640605.172 [ElevatorServo](INFO): Powering down
2025-03-10T21:03:25.173Z,1741640605.173 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-10T21:03:25.173Z,1741640605.173 [MassServo](INFO): Powering down
2025-03-10T21:03:25.173Z,1741640605.173 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-10T21:03:25.174Z,1741640605.174 [RudderServo](INFO): Powering down
2025-03-10T21:03:25.174Z,1741640605.174 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-10T21:03:25.174Z,1741640605.174 [ThrusterHE](INFO): Powering down
2025-03-10T21:03:25.176Z,1741640605.176 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-10T21:03:25.176Z,1741640605.176 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-10T21:03:25.176Z,1741640605.176 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-10T21:03:25.176Z,1741640605.176 [CBIT](DEBUG): Powering off loads.
2025-03-10T21:03:25.187Z,1741640605.187 [CBIT](DEBUG): Disabling WDT.
2025-03-10T21:03:25.199Z,1741640605.199 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-10T21:03:25.200Z,1741640605.200 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-10T21:03:25.200Z,1741640605.200 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.256Z,1741640605.256 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.259Z,1741640605.259 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.265Z,1741640605.265 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.272Z,1741640605.272 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.345Z,1741640605.345 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.349Z,1741640605.349 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.373Z,1741640605.373 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-10T21:03:25.373Z,1741640605.373 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-10T21:03:25.393Z,1741640605.393 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T21:03:25.456Z,1741640605.456 [logger ThreadHandler](INFO): Thread cancelled.