2025-07-18T13:29:25.523Z,1752845365.523 [Supervisor](DEBUG): Initializing supervisor.
2025-07-18T13:29:25.527Z,1752845365.527 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-07-18T13:29:25.527Z,1752845365.527 [SyncHandler](INFO): Protected caller Thread ID is 1437
2025-07-18T13:29:25.528Z,1752845365.528 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-07-18T13:29:25.529Z,1752845365.529 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-07-18T13:29:25.529Z,1752845365.529 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1438
2025-07-18T13:29:25.533Z,1752845365.533 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-07-18T13:29:25.553Z,1752845365.553 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-07-18T13:29:25.554Z,1752845365.554 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-07-18T13:29:25.555Z,1752845365.555 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1439
2025-07-18T13:29:25.559Z,1752845365.559 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-07-18T13:29:25.560Z,1752845365.560 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-07-18T13:29:25.560Z,1752845365.560 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1440
2025-07-18T13:29:25.563Z,1752845365.563 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-07-18T13:29:25.564Z,1752845365.564 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-07-18T13:29:25.564Z,1752845365.564 [logger ThreadHandler](INFO): Protected caller Thread ID is 1441
2025-07-18T13:29:25.568Z,1752845365.568 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-07-18T13:29:25.568Z,1752845365.568 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-07-18T13:29:25.573Z,1752845365.573 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-07-18T13:29:26.150Z,1752845366.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-07-18T13:29:26.151Z,1752845366.151 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-07-18T13:29:26.380Z,1752845366.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-07-18T13:29:26.382Z,1752845366.382 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-07-18T13:29:26.483Z,1752845366.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-07-18T13:29:26.484Z,1752845366.484 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-07-18T13:29:26.738Z,1752845366.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-07-18T13:29:26.739Z,1752845366.739 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-07-18T13:29:26.829Z,1752845366.829 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-07-18T13:29:26.937Z,1752845366.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-07-18T13:29:26.938Z,1752845366.938 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-07-18T13:29:27.317Z,1752845367.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-07-18T13:29:27.318Z,1752845367.318 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-07-18T13:29:27.465Z,1752845367.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-07-18T13:29:27.465Z,1752845367.465 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-07-18T13:29:28.034Z,1752845368.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-07-18T13:29:28.035Z,1752845368.035 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-07-18T13:29:28.233Z,1752845368.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-07-18T13:29:28.234Z,1752845368.234 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-07-18T13:29:28.690Z,1752845368.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-07-18T13:29:28.691Z,1752845368.691 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-07-18T13:29:28.996Z,1752845368.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-07-18T13:29:28.997Z,1752845368.997 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-07-18T13:29:29.121Z,1752845369.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-07-18T13:29:29.121Z,1752845369.121 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-07-18T13:29:30.057Z,1752845370.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-07-18T13:29:30.658Z,1752845370.658 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-07-18T13:29:31.559Z,1752845371.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-07-18T13:29:31.559Z,1752845371.559 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-07-18T13:29:31.823Z,1752845371.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-07-18T13:29:31.825Z,1752845371.825 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2025-07-18T13:29:31.827Z,1752845371.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2025-07-18T13:29:31.959Z,1752845371.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2025-07-18T13:29:32.055Z,1752845372.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2025-07-18T13:29:32.205Z,1752845372.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2025-07-18T13:29:32.302Z,1752845372.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2025-07-18T13:29:32.590Z,1752845372.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-07-18T13:29:32.591Z,1752845372.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2025-07-18T13:29:32.708Z,1752845372.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2025-07-18T13:29:32.827Z,1752845372.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2025-07-18T13:29:32.939Z,1752845372.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2025-07-18T13:29:33.106Z,1752845373.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2025-07-18T13:29:33.215Z,1752845373.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2025-07-18T13:29:33.312Z,1752845373.312 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-07-18T13:29:33.322Z,1752845373.322 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-07-18T13:29:33.334Z,1752845373.334 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-07-18T13:29:33.334Z,1752845373.334 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-07-18T13:29:33.480Z,1752845373.480 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-07-18T13:29:33.480Z,1752845373.480 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-07-18T13:29:33.540Z,1752845373.540 [VerticalControl](DEBUG): Construct VerticalControl.
2025-07-18T13:29:33.604Z,1752845373.604 [VerticalControl] Loaded
2025-07-18T13:29:33.604Z,1752845373.604 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-07-18T13:29:33.607Z,1752845373.607 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-07-18T13:29:33.656Z,1752845373.656 [HorizontalControl] Loaded
2025-07-18T13:29:33.657Z,1752845373.657 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-07-18T13:29:33.659Z,1752845373.659 [SpeedControl](DEBUG): Construct SpeedControl.
2025-07-18T13:29:33.663Z,1752845373.663 [SpeedControl] Loaded
2025-07-18T13:29:33.663Z,1752845373.663 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-07-18T13:29:33.666Z,1752845373.666 [LoopControl](DEBUG): Construct LoopControl.
2025-07-18T13:29:33.666Z,1752845373.666 [LoopControl] Loaded
2025-07-18T13:29:33.666Z,1752845373.666 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-07-18T13:29:33.667Z,1752845373.667 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-07-18T13:29:33.667Z,1752845373.667 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-07-18T13:29:33.838Z,1752845373.838 [BuoyancyServo] Loaded
2025-07-18T13:29:33.839Z,1752845373.839 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-07-18T13:29:33.862Z,1752845373.862 [ElevatorServo] Loaded
2025-07-18T13:29:33.863Z,1752845373.862 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-07-18T13:29:33.885Z,1752845373.885 [MassServo] Loaded
2025-07-18T13:29:33.885Z,1752845373.885 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-07-18T13:29:33.906Z,1752845373.906 [RudderServo] Loaded
2025-07-18T13:29:33.907Z,1752845373.907 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-07-18T13:29:33.924Z,1752845373.924 [ThrusterHE] Loaded
2025-07-18T13:29:33.924Z,1752845373.924 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-07-18T13:29:33.925Z,1752845373.925 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-07-18T13:29:33.925Z,1752845373.925 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-07-18T13:29:33.980Z,1752845373.980 [DepthRateCalculator] Loaded
2025-07-18T13:29:33.980Z,1752845373.980 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-07-18T13:29:33.985Z,1752845373.985 [PitchRateCalculator] Loaded
2025-07-18T13:29:33.986Z,1752845373.986 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-07-18T13:29:33.996Z,1752845373.996 [SpeedCalculator] Loaded
2025-07-18T13:29:33.996Z,1752845373.996 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-07-18T13:29:34.001Z,1752845374.001 [YawRateCalculator] Loaded
2025-07-18T13:29:34.001Z,1752845374.001 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-07-18T13:29:34.020Z,1752845374.020 [ElevatorOffsetCalculator] Loaded
2025-07-18T13:29:34.021Z,1752845374.021 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-07-18T13:29:34.021Z,1752845374.021 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-07-18T13:29:34.022Z,1752845374.022 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-07-18T13:29:34.143Z,1752845374.143 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-07-18T13:29:34.144Z,1752845374.144 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-07-18T13:29:34.158Z,1752845374.158 [NavChart] Loaded
2025-07-18T13:29:34.159Z,1752845374.159 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-07-18T13:29:34.164Z,1752845374.164 [UniversalFixResidualReporter] Loaded
2025-07-18T13:29:34.165Z,1752845374.165 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-07-18T13:29:34.165Z,1752845374.165 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-07-18T13:29:34.166Z,1752845374.166 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-07-18T13:29:34.856Z,1752845374.856 [AHRS_M2] Loaded
2025-07-18T13:29:34.857Z,1752845374.857 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-07-18T13:29:34.976Z,1752845374.976 [BackseatComponent] Loaded
2025-07-18T13:29:34.977Z,1752845374.977 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-07-18T13:29:34.992Z,1752845374.992 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409294E0
2025-07-18T13:29:34.993Z,1752845374.993 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1523
2025-07-18T13:29:34.996Z,1752845374.996 [LcmUniversalReporter] Loaded
2025-07-18T13:29:34.997Z,1752845374.997 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-07-18T13:29:35.035Z,1752845375.035 [BioacousticsDataBridge] Loaded
2025-07-18T13:29:35.035Z,1752845375.035 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-07-18T13:29:36.978Z,1752845376.978 [BPC1] Loaded
2025-07-18T13:29:36.979Z,1752845376.979 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-07-18T13:29:37.082Z,1752845377.082 [DAT] Loaded
2025-07-18T13:29:37.082Z,1752845377.082 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-07-18T13:29:37.083Z,1752845377.083 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409594E0
2025-07-18T13:29:37.084Z,1752845377.084 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1524
2025-07-18T13:29:37.159Z,1752845377.159 [DataOverHttps] Loaded
2025-07-18T13:29:37.160Z,1752845377.160 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-07-18T13:29:37.161Z,1752845377.161 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2025-07-18T13:29:37.161Z,1752845377.161 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1525
2025-07-18T13:29:37.186Z,1752845377.186 [Depth_Keller] Loaded
2025-07-18T13:29:37.186Z,1752845377.186 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-07-18T13:29:37.191Z,1752845377.191 [DropWeight] Loaded
2025-07-18T13:29:37.192Z,1752845377.192 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-07-18T13:29:37.260Z,1752845377.260 [NAL9602] Loaded
2025-07-18T13:29:37.260Z,1752845377.260 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-07-18T13:29:37.291Z,1752845377.291 [Onboard] Loaded
2025-07-18T13:29:37.291Z,1752845377.291 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-07-18T13:29:37.292Z,1752845377.292 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2025-07-18T13:29:37.293Z,1752845377.293 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1526
2025-07-18T13:29:37.308Z,1752845377.308 [Power24vConverter] Loaded
2025-07-18T13:29:37.308Z,1752845377.308 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-07-18T13:29:37.327Z,1752845377.327 [Radio_Surface] Loaded
2025-07-18T13:29:37.327Z,1752845377.327 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-07-18T13:29:37.328Z,1752845377.328 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0
2025-07-18T13:29:37.328Z,1752845377.328 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1527
2025-07-18T13:29:37.343Z,1752845377.343 [Sonardyne_Nano] Loaded
2025-07-18T13:29:37.343Z,1752845377.343 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-07-18T13:29:37.344Z,1752845377.344 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-07-18T13:29:37.344Z,1752845377.344 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-07-18T13:29:37.409Z,1752845377.409 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-07-18T13:29:37.409Z,1752845377.409 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-07-18T13:29:37.607Z,1752845377.607 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-07-18T13:29:37.608Z,1752845377.608 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-07-18T13:29:37.639Z,1752845377.639 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-07-18T13:29:37.640Z,1752845377.640 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-07-18T13:29:37.825Z,1752845377.825 [CTD_Seabird] Loaded
2025-07-18T13:29:37.825Z,1752845377.825 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-07-18T13:29:37.826Z,1752845377.826 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B314E0
2025-07-18T13:29:37.827Z,1752845377.827 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1528
2025-07-18T13:29:37.850Z,1752845377.850 [PAR_Licor] Loaded
2025-07-18T13:29:37.850Z,1752845377.850 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-07-18T13:29:37.898Z,1752845377.898 [WetLabsBB2FL] Loaded
2025-07-18T13:29:37.898Z,1752845377.898 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-07-18T13:29:37.899Z,1752845377.899 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B614E0
2025-07-18T13:29:37.899Z,1752845377.899 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1529
2025-07-18T13:29:37.900Z,1752845377.900 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-07-18T13:29:37.901Z,1752845377.901 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-07-18T13:29:38.278Z,1752845378.278 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-07-18T13:29:38.279Z,1752845378.279 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-07-18T13:29:38.456Z,1752845378.456 [SBIT](DEBUG): Construct Startup Built In Test.
2025-07-18T13:29:38.466Z,1752845378.466 [SBIT] Loaded
2025-07-18T13:29:38.466Z,1752845378.466 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-07-18T13:29:38.469Z,1752845378.469 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-07-18T13:29:38.483Z,1752845378.483 [IBIT] Loaded
2025-07-18T13:29:38.483Z,1752845378.483 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-07-18T13:29:38.488Z,1752845378.488 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-07-18T13:29:38.573Z,1752845378.573 [CBIT] Loaded
2025-07-18T13:29:38.573Z,1752845378.573 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-07-18T13:29:38.607Z,1752845378.607 [GFScanner] Loaded
2025-07-18T13:29:38.608Z,1752845378.608 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-07-18T13:29:38.608Z,1752845378.608 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-07-18T13:29:38.615Z,1752845378.615 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-07-18T13:29:38.617Z,1752845378.617 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-07-18T13:29:38.629Z,1752845378.629 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-07-18T13:29:38.630Z,1752845378.630 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-07-18T13:29:38.631Z,1752845378.631 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1530
2025-07-18T13:29:38.635Z,1752845378.635 [Supervisor](INFO): Main Thread ID is 830
2025-07-18T13:29:38.635Z,1752845378.635 [Supervisor](DEBUG): Running supervisor.
2025-07-18T13:29:38.636Z,1752845378.636 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1531
2025-07-18T13:29:38.636Z,1752845378.636 [CommandExec](INFO): Initializing the command executive.
2025-07-18T13:29:38.638Z,1752845378.638 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1532
2025-07-18T13:29:38.640Z,1752845378.640 [controlThread ThreadHandler](INFO): Handler Thread ID is 1533
2025-07-18T13:29:38.641Z,1752845378.641 [controlThread](DEBUG): Initializing ControlThread
2025-07-18T13:29:38.641Z,1752845378.641 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-07-18T13:29:38.643Z,1752845378.643 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-07-18T13:29:38.644Z,1752845378.644 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-07-18T13:29:38.644Z,1752845378.644 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-07-18T13:29:38.646Z,1752845378.646 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-07-18T13:29:38.646Z,1752845378.646 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-07-18T13:29:38.646Z,1752845378.646 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-07-18T13:29:38.647Z,1752845378.647 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-07-18T13:29:38.647Z,1752845378.647 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-07-18T13:29:38.648Z,1752845378.648 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-07-18T13:29:38.649Z,1752845378.649 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-07-18T13:29:38.654Z,1752845378.654 [SBIT](INFO): Initialize SBIT Component.
2025-07-18T13:29:38.655Z,1752845378.655 [SBIT](IMPORTANT): git: 2025-07-16
2025-07-18T13:29:38.655Z,1752845378.655 [SBIT](INFO): git hash: d69d2715ddd00fa751675e8858faf22d094d5d81
2025-07-18T13:29:38.655Z,1752845378.655 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-07-18T13:29:38.656Z,1752845378.656 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-07-18T13:29:38.657Z,1752845378.657 [SBIT](INFO): Beginning SBIT in 26.000000 seconds.
2025-07-18T13:29:38.658Z,1752845378.658 [IBIT](INFO): Initialize IBIT Component.
2025-07-18T13:29:38.659Z,1752845378.659 [CBIT](DEBUG): Initialize CBIT Component.
2025-07-18T13:29:38.660Z,1752845378.660 [logger ThreadHandler](INFO): Handler Thread ID is 1534
2025-07-18T13:29:38.670Z,1752845378.670 [CBIT](DEBUG): Initialized mux pins.
2025-07-18T13:29:38.670Z,1752845378.670 [CBIT](DEBUG): Initializing the watchdog timer.
2025-07-18T13:29:38.679Z,1752845378.679 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1535
2025-07-18T13:29:38.691Z,1752845378.691 [DAT ThreadHandler](INFO): Handler Thread ID is 1536
2025-07-18T13:29:38.691Z,1752845378.691 [DAT](INFO): Powering up
2025-07-18T13:29:38.691Z,1752845378.691 [DAT](DEBUG): Initializing DAT.
2025-07-18T13:29:38.694Z,1752845378.694 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-07-18T13:29:38.695Z,1752845378.695 [CBIT](DEBUG): Initializing heartbeat.
2025-07-18T13:29:38.696Z,1752845378.696 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1538
2025-07-18T13:29:38.698Z,1752845378.698 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-07-18T13:29:38.707Z,1752845378.707 [Onboard ThreadHandler](INFO): Handler Thread ID is 1539
2025-07-18T13:29:38.730Z,1752845378.730 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1540
2025-07-18T13:29:38.750Z,1752845378.750 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1541
2025-07-18T13:29:38.751Z,1752845378.751 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-07-18T13:29:38.759Z,1752845378.759 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1543
2025-07-18T13:29:38.763Z,1752845378.763 [WetLabsBB2FL](INFO): Powering up
2025-07-18T13:29:38.764Z,1752845378.764 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1545
2025-07-18T13:29:38.769Z,1752845378.769 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2025-07-18T13:29:38.769Z,1752845378.769 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2025-07-18T13:29:38.769Z,1752845378.769 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2025-07-18T13:29:38.769Z,1752845378.769 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2025-07-18T13:29:38.770Z,1752845378.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2025-07-18T13:29:38.770Z,1752845378.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2025-07-18T13:29:38.770Z,1752845378.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2025-07-18T13:29:38.770Z,1752845378.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2025-07-18T13:29:38.771Z,1752845378.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2025-07-18T13:29:38.771Z,1752845378.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2025-07-18T13:29:38.771Z,1752845378.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2025-07-18T13:29:38.771Z,1752845378.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2025-07-18T13:29:38.771Z,1752845378.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2025-07-18T13:29:38.772Z,1752845378.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2025-07-18T13:29:38.772Z,1752845378.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2025-07-18T13:29:38.772Z,1752845378.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2025-07-18T13:29:38.772Z,1752845378.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2025-07-18T13:29:38.772Z,1752845378.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2025-07-18T13:29:38.772Z,1752845378.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2025-07-18T13:29:38.772Z,1752845378.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2025-07-18T13:29:38.774Z,1752845378.774 [CBIT](DEBUG): Deactivating emergency mode.
2025-07-18T13:29:38.814Z,1752845378.814 [CBIT](DEBUG): Backplane powered.
2025-07-18T13:29:38.814Z,1752845378.814 [GFScanner](DEBUG): Initializing GFScanner
2025-07-18T13:29:38.815Z,1752845378.815 [GFScanner](DEBUG): Deactivating GF circuits.
2025-07-18T13:29:38.819Z,1752845378.819 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-07-18T13:29:38.820Z,1752845378.820 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-07-18T13:29:38.820Z,1752845378.820 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-07-18T13:29:38.824Z,1752845378.824 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-07-18T13:29:38.877Z,1752845378.877 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-07-18T13:29:38.886Z,1752845378.886 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-18T13:29:38.934Z,1752845378.934 [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-07-18T13:29:38.935Z,1752845378.935 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-07-18T13:29:38.935Z,1752845378.935 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-07-18T13:29:38.938Z,1752845378.938 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-07-18T13:29:39.134Z,1752845379.134 [Radio_Surface](INFO): Powering up
2025-07-18T13:29:39.249Z,1752845379.249 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-07-18T13:29:39.264Z,1752845379.264 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-07-18T13:29:39.265Z,1752845379.265 [Default:A.Wait](DEBUG): Construct Wait.
2025-07-18T13:29:39.267Z,1752845379.267 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-18T13:29:39.324Z,1752845379.324 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-07-18T13:29:39.326Z,1752845379.326 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-07-18T13:29:39.348Z,1752845379.348 [Default:E.Execute](DEBUG): Construct Execute.
2025-07-18T13:29:39.352Z,1752845379.352 [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-07-18T13:29:39.363Z,1752845379.363 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-07-18T13:29:39.387Z,1752845379.387 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-07-18T13:29:39.451Z,1752845379.451 [Depth_Keller](INFO): Initializing.
2025-07-18T13:29:39.452Z,1752845379.452 [Power24vConverter](INFO): Powering up.
2025-07-18T13:29:39.453Z,1752845379.453 [Sonardyne_Nano](INFO): Initializing.
2025-07-18T13:29:39.498Z,1752845379.498 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-18T13:29:39.521Z,1752845379.521 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:29:39.526Z,1752845379.526 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-07-18T13:29:39.527Z,1752845379.527 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:29:39.534Z,1752845379.534 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:29:39.535Z,1752845379.535 [MassServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:29:39.542Z,1752845379.542 [MassServo](DEBUG): Initializing MassServo.
2025-07-18T13:29:39.543Z,1752845379.543 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:29:39.550Z,1752845379.550 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-18T13:29:39.551Z,1752845379.551 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-07-18T13:29:39.558Z,1752845379.558 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-07-18T13:29:41.398Z,1752845381.398 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-07-18T13:29:41.402Z,1752845381.402 [WetLabsBB2FL](INFO): Powering down
2025-07-18T13:29:43.039Z,1752845383.039 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2025-07-18T13:29:45.782Z,1752845385.782 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-07-18T13:29:51.039Z,1752845391.039 [DAT](INFO): DAT read:
2025-07-18T13:29:51.040Z,1752845391.040 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-07-18T13:29:52.803Z,1752845392.803 [DAT](INFO): DAT read: MF Frequency Band
2025-07-18T13:29:52.804Z,1752845392.804 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-07-18T13:29:52.805Z,1752845392.805 [DAT](INFO): DAT read: Jul 18 2025 13:29:47
2025-07-18T13:29:53.811Z,1752845393.811 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-07-18T13:29:53.813Z,1752845393.813 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-07-18T13:29:53.813Z,1752845393.813 [DAT](INFO): commRate: 800
2025-07-18T13:29:55.878Z,1752845395.878 [DAT](INFO): entering command mode
2025-07-18T13:29:56.079Z,1752845396.079 [DAT](INFO): DAT read:
2025-07-18T13:29:56.079Z,1752845396.079 [DAT](INFO): DAT read: user:1>
2025-07-18T13:29:56.080Z,1752845396.080 [DAT](INFO): setting verbose to 3
2025-07-18T13:29:56.331Z,1752845396.331 [DAT](INFO): DAT read: user:1>
2025-07-18T13:29:56.332Z,1752845396.332 [DAT](INFO): DAT read: Verbose | 3
2025-07-18T13:29:56.332Z,1752845396.332 [DAT](INFO): set verbose to 3
2025-07-18T13:29:56.332Z,1752845396.332 [DAT](INFO): setting DatVerbose to 27440
2025-07-18T13:29:56.583Z,1752845396.583 [DAT](INFO): DAT read: user:2>
2025-07-18T13:29:56.584Z,1752845396.584 [DAT](INFO): DAT read: DatVerbose | 27440
2025-07-18T13:29:56.584Z,1752845396.584 [DAT](INFO): set DatVerbose to 27440
2025-07-18T13:29:56.585Z,1752845396.585 [DAT](INFO): setting transmit power to 8
2025-07-18T13:29:56.835Z,1752845396.835 [DAT](INFO): DAT read: user:3>
2025-07-18T13:29:56.836Z,1752845396.836 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-07-18T13:29:56.836Z,1752845396.836 [DAT](INFO): set transmit power to 8
2025-07-18T13:29:56.836Z,1752845396.836 [DAT](INFO): setting local address to 12
2025-07-18T13:29:57.087Z,1752845397.087 [DAT](INFO): DAT read: user:4>
2025-07-18T13:29:57.088Z,1752845397.088 [DAT](INFO): DAT read: LocalAddr | 12
2025-07-18T13:29:57.088Z,1752845397.088 [DAT](INFO): set local address to 12
2025-07-18T13:29:57.089Z,1752845397.089 [DAT](INFO): Setting time to: 13:29:57 And date to:7/18/2025
2025-07-18T13:29:57.339Z,1752845397.339 [DAT](INFO): DAT read: user:5>
2025-07-18T13:29:57.340Z,1752845397.340 [DAT](INFO): DAT read: Fri Jul 18, 2025 13:29:57
2025-07-18T13:29:57.340Z,1752845397.340 [DAT](INFO): Local DAT time set to Fri Jul 18, 2025 13:29:57
2025-07-18T13:30:05.333Z,1752845405.333 [SBIT](IMPORTANT): Beginning Startup BIT
2025-07-18T13:30:05.337Z,1752845405.337 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-18T13:30:07.358Z,1752845407.358 [NAL9602](INFO): Powering up NAL9602
2025-07-18T13:30:07.806Z,1752845407.806 [ElevatorServo](FAULT): Overload Error
2025-07-18T13:30:07.806Z,1752845407.806 [ElevatorServo] Hardware Fault, FailCount= 1
2025-07-18T13:30:07.806Z,1752845407.806 [ElevatorServo](ERROR): Hardware Fault
2025-07-18T13:30:07.975Z,1752845407.975 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-07-18T13:30:08.196Z,1752845408.196 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:30:08.197Z,1752845408.197 [ElevatorServo](INFO): Powering down
2025-07-18T13:30:08.987Z,1752845408.987 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:30:09.103Z,1752845409.103 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:30:09.163Z,1752845409.163 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-07-18T13:30:09.163Z,1752845409.163 [ElevatorServo] No Fault, FailCount= 1
2025-07-18T13:30:09.878Z,1752845409.878 [GFScanner](IMPORTANT): Low side ground fault detected
mA:
CHAN A0 (Batt): 0.797465
CHAN A1 (24V): 0.781659
CHAN A2 (12V): 0.478677
CHAN A3 (5V): 0.047276
CHAN B0 (3.3V): 0.146163
CHAN B1 (3.15aV): 0.153143
CHAN B2 (3.15bV): 0.111427
CHAN B3 (GND): -0.063865
OPEN: 0.016907
Full Scale: +/- 1 mA
2025-07-18T13:30:14.266Z,1752845414.266 [ElevatorServo](FAULT): Overload Error
2025-07-18T13:30:14.266Z,1752845414.266 [ElevatorServo] Hardware Fault, FailCount= 2
2025-07-18T13:30:14.266Z,1752845414.266 [ElevatorServo](ERROR): Hardware Fault
2025-07-18T13:30:14.399Z,1752845414.399 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-07-18T13:30:14.689Z,1752845414.689 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:30:14.689Z,1752845414.689 [ElevatorServo](INFO): Powering down
2025-07-18T13:30:15.455Z,1752845415.455 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:30:15.571Z,1752845415.571 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:30:15.576Z,1752845415.576 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-07-18T13:30:15.576Z,1752845415.576 [ElevatorServo] No Fault, FailCount= 2
2025-07-18T13:30:18.265Z,1752845418.265 [NAL9602](INFO): NAL9602 initialized
2025-07-18T13:30:18.686Z,1752845418.686 [SBIT](FAULT): Elevator: EXPECTED:15.000000 ACTUAL:6.596051
2025-07-18T13:30:20.738Z,1752845420.738 [ElevatorServo](FAULT): Overload Error
2025-07-18T13:30:20.738Z,1752845420.738 [ElevatorServo] Hardware Fault, FailCount= 3
2025-07-18T13:30:20.738Z,1752845420.738 [ElevatorServo](ERROR): Hardware Fault
2025-07-18T13:30:21.095Z,1752845421.095 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-07-18T13:30:21.095Z,1752845421.095 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2025-07-18T13:30:21.166Z,1752845421.166 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:30:21.166Z,1752845421.166 [ElevatorServo](INFO): Powering down
2025-07-18T13:30:21.333Z,1752845421.333 [CommandExec](FAULT): Scheduling is paused
2025-07-18T13:30:21.333Z,1752845421.333 [CBIT](INFO): Critical error at 20250718T133021
2025-07-18T13:30:21.333Z,1752845421.333 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-07-18T13:30:21.932Z,1752845421.932 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:30:22.051Z,1752845422.051 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:30:36.059Z,1752845436.059 [NAL9602](INFO): SBD MO Status=0, MOMSN=10174, MT Status=0, MTMSN=0
2025-07-18T13:30:36.059Z,1752845436.059 [NAL9602](INFO): No messages in MT queue
2025-07-18T13:30:45.378Z,1752845445.378 [SBIT](ERROR): Could not read elevatorAngleReader_.
2025-07-18T13:30:45.398Z,1752845445.398 [SBIT](FAULT): Control surface position failure.
2025-07-18T13:30:51.821Z,1752845451.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133050.00,A,3647.91372,N,12151.04190,W,0.233,330.70,180725,,,A*76
2025-07-18T13:30:51.825Z,1752845451.825 [NAL9602](INFO): GPS fix at 20250718T133050: (36.798562, -121.850698)
2025-07-18T13:30:59.116Z,1752845459.116 [SBIT](ERROR): Could not read elevatorAngleReader_.
2025-07-18T13:30:59.116Z,1752845459.116 [SBIT](FAULT): Control surface position failure.
2025-07-18T13:30:59.529Z,1752845459.529 [SBIT](CRITICAL): SBIT FAILED
2025-07-18T13:30:59.530Z,1752845459.530 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-07-18T13:30:59.534Z,1752845459.534 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2025-07-18T13:30:59.535Z,1752845459.535 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;
2025-07-18T13:30:59.535Z,1752845459.535 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;
2025-07-18T13:30:59.535Z,1752845459.535 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2025-07-18T13:30:59.535Z,1752845459.535 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 1.000000 count_per_second;
2025-07-18T13:30:59.535Z,1752845459.535 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=245.648064 cubic_centimeter;
2025-07-18T13:30:59.535Z,1752845459.535 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.746382 millimeter;
2025-07-18T13:30:59.535Z,1752845459.535 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;
2025-07-18T13:30:59.537Z,1752845459.537 [CBIT](INFO): Critical error at 20250718T133059
2025-07-18T13:31:00.031Z,1752845460.031 [MissionManager](IMPORTANT): Started mission Startup
2025-07-18T13:31:00.031Z,1752845460.031 [Startup] Running Loop=1
2025-07-18T13:31:00.032Z,1752845460.032 [Startup](DEBUG): Aggregate::initialize Startup
2025-07-18T13:31:00.032Z,1752845460.032 [Startup:A.GoToSurface] Running Loop=1
2025-07-18T13:31:00.032Z,1752845460.032 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T13:31:00.032Z,1752845460.032 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-18T13:31:00.033Z,1752845460.033 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-18T13:31:00.033Z,1752845460.033 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-18T13:31:00.033Z,1752845460.033 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-18T13:31:00.034Z,1752845460.034 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T13:31:00.034Z,1752845460.034 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T13:31:00.036Z,1752845460.036 [Startup:StartupSatComms] Running Loop=1
2025-07-18T13:31:00.036Z,1752845460.036 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-07-18T13:31:00.036Z,1752845460.036 [Startup:StartupSatComms:A] Running Loop=1
2025-07-18T13:31:00.345Z,1752845460.345 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-07-18T13:31:01.922Z,1752845461.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133100.00,A,3647.91307,N,12151.04283,W,0.583,288.42,180725,,,A*7E
2025-07-18T13:31:01.924Z,1752845461.924 [NAL9602](INFO): GPS fix at 20250718T133100: (36.798551, -121.850714)
2025-07-18T13:31:01.959Z,1752845461.959 [Startup:StartupSatComms:A] Stopped
2025-07-18T13:31:01.959Z,1752845461.959 [Startup:StartupSatComms:B] Running Loop=1
2025-07-18T13:31:02.370Z,1752845462.370 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-18T13:31:26.781Z,1752845486.781 [NAL9602](INFO): SBD MO Status=1, MOMSN=10175, MT Status=0, MTMSN=0
2025-07-18T13:31:26.833Z,1752845486.833 [NAL9602](INFO): Sent 39 bytes from file Logs/20250717T213253/Courier0078.lzma
2025-07-18T13:31:26.833Z,1752845486.833 [NAL9602](INFO): Packets left to send: 0
2025-07-18T13:31:41.301Z,1752845501.301 [NAL9602](INFO): SBD MO Status=1, MOMSN=10176, MT Status=0, MTMSN=0
2025-07-18T13:31:41.358Z,1752845501.358 [NAL9602](INFO): Sent 332 bytes from file Logs/20250718T132925/Courier0000.lzma
2025-07-18T13:31:41.359Z,1752845501.359 [NAL9602](INFO): Packets left to send: 1
2025-07-18T13:31:51.066Z,1752845511.066 [NAL9602](INFO): SBD MO Status=1, MOMSN=10177, MT Status=0, MTMSN=0
2025-07-18T13:31:51.114Z,1752845511.114 [NAL9602](INFO): Sent 17 bytes from file Logs/20250718T132925/Courier0000.lzma
2025-07-18T13:31:51.115Z,1752845511.115 [NAL9602](INFO): Packets left to send: 0
2025-07-18T13:32:02.046Z,1752845522.046 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-18T13:31:01.0Z
2025-07-18T13:32:02.046Z,1752845522.046 [Startup:StartupSatComms:B] Stopped
2025-07-18T13:32:02.047Z,1752845522.047 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-07-18T13:32:02.047Z,1752845522.047 [Startup:StartupSatComms] Stopped
2025-07-18T13:32:02.047Z,1752845522.047 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-07-18T13:32:02.048Z,1752845522.048 [Startup](INFO): Completed Startup
2025-07-18T13:32:02.048Z,1752845522.048 [MissionManager](INFO): Startup is completed.
2025-07-18T13:32:02.048Z,1752845522.048 [MissionManager](INFO): Uninitializing Mission Startup
2025-07-18T13:32:02.048Z,1752845522.048 [Startup] Stopped
2025-07-18T13:32:02.048Z,1752845522.048 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-07-18T13:32:02.048Z,1752845522.048 [Startup:A.GoToSurface] Stopped
2025-07-18T13:32:02.048Z,1752845522.048 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T13:32:02.446Z,1752845522.446 [MissionManager](IMPORTANT): Started mission Default
2025-07-18T13:32:02.466Z,1752845522.466 [Default] Running Loop=1
2025-07-18T13:32:02.466Z,1752845522.466 [Default](DEBUG): Aggregate::initialize Default
2025-07-18T13:32:02.466Z,1752845522.466 [Default:B.GoToSurface] Running Loop=1
2025-07-18T13:32:02.466Z,1752845522.466 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T13:32:02.467Z,1752845522.467 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-18T13:32:02.467Z,1752845522.467 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-18T13:32:02.467Z,1752845522.467 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-18T13:32:02.468Z,1752845522.468 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-18T13:32:02.468Z,1752845522.468 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T13:32:02.468Z,1752845522.468 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T13:32:02.468Z,1752845522.468 [Default:A.Wait] Running Loop=1
2025-07-18T13:32:02.469Z,1752845522.469 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-18T13:32:15.772Z,1752845535.772 [Default:A.Wait](INFO): Done Waiting.
2025-07-18T13:32:15.772Z,1752845535.772 [Default:A.Wait] Stopped
2025-07-18T13:32:15.772Z,1752845535.772 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T13:32:16.180Z,1752845536.180 [Default:CheckIn] Running Loop=1
2025-07-18T13:32:16.180Z,1752845536.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-18T13:32:16.180Z,1752845536.180 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-18T13:32:16.592Z,1752845536.592 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-18T13:32:29.096Z,1752845549.096 [NAL9602](INFO): SBD MO Status=1, MOMSN=10178, MT Status=0, MTMSN=0
2025-07-18T13:32:29.158Z,1752845549.158 [NAL9602](INFO): Sent 287 bytes from file Logs/20250717T213253/Express0080.lzma
2025-07-18T13:32:29.159Z,1752845549.159 [NAL9602](INFO): Packets left to send: 0
2025-07-18T13:32:30.313Z,1752845550.313 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133228.00,A,3647.91198,N,12151.04549,W,0.603,196.80,180725,,,A*7B
2025-07-18T13:32:30.315Z,1752845550.315 [NAL9602](INFO): GPS fix at 20250718T133228: (36.798533, -121.850758)
2025-07-18T13:32:30.326Z,1752845550.326 [Default:CheckIn:Read_GPS] Stopped
2025-07-18T13:32:30.326Z,1752845550.326 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-18T13:32:30.743Z,1752845550.743 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-18T13:33:01.816Z,1752845581.816 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2025-07-18T13:33:01.816Z,1752845581.816 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-18T13:33:04.543Z,1752845584.543 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-18T13:33:19.345Z,1752845599.345 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2025-07-18T13:33:19.345Z,1752845599.345 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-18T13:33:27.176Z,1752845607.176 [DataOverHttps](IMPORTANT): SBD MTMSN=20250718T133326
2025-07-18T13:33:27.180Z,1752845607.180 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004010
2025-07-18T13:33:34.614Z,1752845614.614 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250718T132925/Courier0004.lzma
2025-07-18T13:33:34.616Z,1752845614.616 [DataOverHttps](INFO): Received command: ! echo i > /dev/loadA4
2025-07-18T13:33:34.621Z,1752845614.621 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA4
2025-07-18T13:33:36.766Z,1752845616.766 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2025-07-18T13:33:36.766Z,1752845616.766 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-18T13:33:45.656Z,1752845625.656 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Courier0004.lzma.bak
2025-07-18T13:33:45.657Z,1752845625.657 [DataOverHttps](IMPORTANT): SBD MOMSN=25545036, MTMSN=20250718T133344
2025-07-18T13:33:53.042Z,1752845633.042 [DataOverHttps](INFO): Received command: gfscan
2025-07-18T13:33:53.063Z,1752845633.063 [CommandExec](IMPORTANT): got command gfscan
2025-07-18T13:33:53.355Z,1752845633.355 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-18T13:33:57.795Z,1752845637.795 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.172141
CHAN A1 (24V): 0.270817
CHAN A2 (12V): 0.126864
CHAN A3 (5V): 0.044762
CHAN B0 (3.3V): 0.031165
CHAN B1 (3.15aV): 0.030498
CHAN B2 (3.15bV): 0.030541
CHAN B3 (GND): 0.000555
OPEN: 0.013627
Full Scale: +/- 1 mA
2025-07-18T13:34:04.086Z,1752845644.086 [DataOverHttps](IMPORTANT): SBD MTMSN=20250718T133403
2025-07-18T13:34:07.471Z,1752845647.471 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T13:34:11.713Z,1752845651.713 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250718T132925/Courier0007.lzma
2025-07-18T13:34:11.716Z,1752845651.716 [DataOverHttps](INFO): Received command: configset verticalcontrol.kdpitchmass 0.25 s persist;configset verticalcontrol.kipitchmass 0.25 1/s persist;configset verticalcontrol.kppitchmass 0.25 n/a persist;configset verticalcontrol.massonlyforceint 1 bool persist
2025-07-18T13:34:13.657Z,1752845653.657 [CommandExec](IMPORTANT): got command configSet VerticalControl.kdPitchMass 0.25 second persist
2025-07-18T13:34:13.663Z,1752845653.663 [CommandExec](IMPORTANT): got command configSet VerticalControl.kiPitchMass 0.25 reciprocal_second persist
2025-07-18T13:34:13.665Z,1752845653.665 [CommandExec](IMPORTANT): got command configSet VerticalControl.kpPitchMass 0.25 none persist
2025-07-18T13:34:13.668Z,1752845653.668 [CommandExec](IMPORTANT): got command configSet VerticalControl.massOnlyForceInt 1 bool persist
2025-07-18T13:34:22.756Z,1752845662.756 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Courier0007.lzma.bak
2025-07-18T13:34:22.756Z,1752845662.756 [DataOverHttps](INFO): SBD MOMSN=25545042
2025-07-18T13:34:37.807Z,1752845677.807 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:34:38.223Z,1752845678.223 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:34:53.215Z,1752845693.215 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250718T132925/Courier0010.lzma
2025-07-18T13:34:53.562Z,1752845693.562 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:34:53.568Z,1752845693.568 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:04.256Z,1752845704.256 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Courier0010.lzma.bak
2025-07-18T13:35:04.256Z,1752845704.256 [DataOverHttps](INFO): SBD MOMSN=25545047
2025-07-18T13:35:08.916Z,1752845708.916 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:08.922Z,1752845708.922 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:24.266Z,1752845724.266 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:24.272Z,1752845724.272 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:30.326Z,1752845730.326 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250718T132925/Courier0013.lzma
2025-07-18T13:35:38.811Z,1752845738.811 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:39.631Z,1752845739.631 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:41.376Z,1752845741.376 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Courier0013.lzma.bak
2025-07-18T13:35:41.376Z,1752845741.376 [DataOverHttps](INFO): SBD MOMSN=25545049
2025-07-18T13:35:54.162Z,1752845754.162 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:35:54.578Z,1752845754.578 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:07.518Z,1752845767.518 [DataOverHttps](INFO): Sending 1224 bytes from file Logs/20250718T132925/Express0001.lzma
2025-07-18T13:36:09.112Z,1752845769.112 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:09.527Z,1752845769.527 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:18.556Z,1752845778.556 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0001.lzma.bak
2025-07-18T13:36:18.556Z,1752845778.556 [DataOverHttps](INFO): SBD MOMSN=25545051
2025-07-18T13:36:24.061Z,1752845784.061 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:24.879Z,1752845784.879 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:39.022Z,1752845799.022 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:40.266Z,1752845800.266 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:44.698Z,1752845804.698 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20250718T132925/Express0005.lzma
2025-07-18T13:36:53.971Z,1752845813.971 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:55.589Z,1752845815.589 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-18T13:36:55.596Z,1752845815.596 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2025-07-18T13:36:55.599Z,1752845815.599 [BPC1](INFO): Received data from all battery sticks.
2025-07-18T13:36:55.740Z,1752845815.740 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0005.lzma.bak
2025-07-18T13:36:55.740Z,1752845815.740 [DataOverHttps](INFO): SBD MOMSN=25545085
2025-07-18T13:37:21.786Z,1752845841.786 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250718T132925/Express0008.lzma
2025-07-18T13:37:32.828Z,1752845852.828 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0008.lzma.bak
2025-07-18T13:37:32.828Z,1752845852.828 [DataOverHttps](INFO): SBD MOMSN=25545088
2025-07-18T13:37:58.670Z,1752845878.670 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20250718T132925/Express0011.lzma
2025-07-18T13:38:09.712Z,1752845889.712 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0011.lzma.bak
2025-07-18T13:38:09.713Z,1752845889.713 [DataOverHttps](INFO): SBD MOMSN=25545092
2025-07-18T13:38:35.874Z,1752845915.874 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20250718T132925/Express0014.lzma
2025-07-18T13:38:46.928Z,1752845926.928 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0014.lzma.bak
2025-07-18T13:38:46.928Z,1752845926.928 [DataOverHttps](INFO): SBD MOMSN=25545097
2025-07-18T13:38:48.394Z,1752845928.394 [Default:CheckIn:Read_Iridium] Stopped
2025-07-18T13:38:48.395Z,1752845928.395 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-18T13:38:48.395Z,1752845928.395 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-18T13:39:05.416Z,1752845945.416 [DataOverHttps](IMPORTANT): SBD MTMSN=20250718T133904
2025-07-18T13:39:12.850Z,1752845952.850 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.Speed 0.9 m/s;run
2025-07-18T13:39:12.907Z,1752845952.907 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-07-18T13:39:12.907Z,1752845952.907 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-07-18T13:39:12.908Z,1752845952.908 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-07-18T13:39:12.909Z,1752845952.909 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-07-18T13:39:14.010Z,1752845954.010 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
4
45
NaN
NaN
30
15
4
0.75
200
10
10
45
2
0
0
1
2
3
NaN
0
Sinking to
Timed out while trying to reach target sink depth at current depth of
2025-07-18T13:39:14.024Z,1752845954.024 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-07-18T13:39:14.029Z,1752845954.029 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-07-18T13:39:14.032Z,1752845954.032 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-07-18T13:39:14.035Z,1752845954.035 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-07-18T13:39:14.038Z,1752845954.038 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-07-18T13:39:14.041Z,1752845954.041 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-07-18T13:39:14.044Z,1752845954.044 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-07-18T13:39:14.048Z,1752845954.048 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-07-18T13:39:14.051Z,1752845954.051 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-07-18T13:39:14.054Z,1752845954.054 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-07-18T13:39:14.057Z,1752845954.057 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-07-18T13:39:14.060Z,1752845954.060 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-07-18T13:39:14.063Z,1752845954.063 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-07-18T13:39:14.086Z,1752845954.086 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-07-18T13:39:14.089Z,1752845954.089 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-07-18T13:39:14.097Z,1752845954.097 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-07-18T13:39:14.101Z,1752845954.101 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-07-18T13:39:14.112Z,1752845954.112 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-07-18T13:39:14.126Z,1752845954.126 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-07-18T13:39:14.129Z,1752845954.129 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-07-18T13:39:14.130Z,1752845954.130 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-07-18T13:39:14.130Z,1752845954.130 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-07-18T13:39:14.135Z,1752845954.135 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-07-18T13:39:14.573Z,1752845954.573 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-07-18T13:39:14.581Z,1752845954.581 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-07-18T13:39:14.584Z,1752845954.584 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-07-18T13:39:14.587Z,1752845954.587 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-07-18T13:39:14.590Z,1752845954.590 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-07-18T13:39:14.593Z,1752845954.593 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-07-18T13:39:14.596Z,1752845954.596 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-07-18T13:39:14.600Z,1752845954.600 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-07-18T13:39:14.603Z,1752845954.603 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-07-18T13:39:14.609Z,1752845954.609 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-18T13:39:14.717Z,1752845954.717 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-07-18T13:39:14.717Z,1752845954.717 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-07-18T13:39:14.726Z,1752845954.726 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-07-18T13:39:15.060Z,1752845955.060 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-07-18T13:39:15.073Z,1752845955.073 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-07-18T13:39:15.084Z,1752845955.084 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-07-18T13:39:15.089Z,1752845955.089 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-07-18T13:39:15.101Z,1752845955.101 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-07-18T13:39:15.107Z,1752845955.107 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-07-18T13:39:15.134Z,1752845955.134 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-07-18T13:39:15.152Z,1752845955.152 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-07-18T13:39:15.170Z,1752845955.170 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-07-18T13:39:15.170Z,1752845955.170 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-07-18T13:39:15.173Z,1752845955.173 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-07-18T13:39:15.215Z,1752845955.215 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-07-18T13:39:15.223Z,1752845955.223 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-07-18T13:39:15.225Z,1752845955.225 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-07-18T13:39:15.232Z,1752845955.232 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-07-18T13:39:15.233Z,1752845955.233 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-07-18T13:39:15.234Z,1752845955.234 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-07-18T13:39:15.496Z,1752845955.496 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-07-18T13:39:15.505Z,1752845955.505 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-07-18T13:39:15.513Z,1752845955.513 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-07-18T13:39:15.517Z,1752845955.517 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-07-18T13:39:15.528Z,1752845955.528 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-07-18T13:39:15.532Z,1752845955.532 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-07-18T13:39:15.560Z,1752845955.560 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-07-18T13:39:15.604Z,1752845955.604 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-07-18T13:39:15.623Z,1752845955.623 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-07-18T13:39:15.632Z,1752845955.632 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-07-18T13:39:15.675Z,1752845955.675 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-07-18T13:39:15.680Z,1752845955.680 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-07-18T13:39:15.694Z,1752845955.694 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-07-18T13:39:15.708Z,1752845955.708 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-07-18T13:39:15.727Z,1752845955.727 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-07-18T13:39:15.751Z,1752845955.751 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-07-18T13:39:15.781Z,1752845955.781 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-07-18T13:39:15.813Z,1752845955.813 [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 keepstation {
"""
Vehicle transits to desired waypoint (or stays put if none is commanded)
and stays within the specified radius.
"""
arguments {
MissionTimeout = 4 hour
"""
Maximum duration of mission
"""
NeedCommsTime = 45 minute
"""
How often to surface for communications
"""
Latitude = NaN degree
"""
Latitude of waypoint to hold. If NaN, hold the latitude at start of
mission.
"""
Longitude = NaN degree
"""
Longitude of waypoint to hold. If NaN, hold the longitude at start of
mission.
"""
Depth = 30 meter
"""
Depth held during drift mode
"""
ApproachDepth = 15 meter
"""
Depth for initial approach to station.
"""
DepthDeadband = 4 meter
"""
How much vertical drift from the specified depth is allowed in drift
mode
"""
Speed = 0.75 meter_per_second
"""
Vehicle transit speed.
"""
Radius = 200 meter
"""
Radius of circle around waypoint to hold.
"""
SinkOffSurface = false
"""
If true, use buoyancy only to decend to SinkDepth before driving to first target depth
"""
SinkDepth = 10 meter
"""
Initial depth to decend using only buoyancy before driving
"""
SinkDepthTimeout = 10 minute
"""
Max wait time for the vehicle to reach the target depth
"""
MaxDepth = 45 meter
"""
Maximum allowable depth during the mission.
"""
MinOffshore = 2 kilometer
"""
Minimum distance from the shoreline to maintain
"""
}
output {
DiveMode = 0 count
"""
Mission variable (don't change). The mission sets this variable to
switch between dive modes. Initialized to DoingComms.
"""
DoingComms = 0 count
"""
Mission variable (don't change). The mission will run in this mode
when doing surface comms.
"""
SurfaceSink = 1 count
"""
Mission variable (don't change). The mission will run in this mode
when sinking from the surface using only buoyancy.
"""
TransitToStation = 2 count
"""
Mission variable (don't change). The mission will run in this mode
when performing the transit to station
"""
StationKeep = 3 count
"""
Mission variable (don't change). The mission will run in this mode
when keeping station
"""
OnStation = false
"""
Mission variable (don't change). Determines last mode (Transit or StationKeep)
to resume after needcomms
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
aggregate SurfaceComms {
run when (
called
or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
)
assign in sequence DiveMode = DoingComms
call priorityHere=false refId="NeedComms"
aggregate setTransit {
run in sequence
break if (
SinkOffSurface
or OnStation
)
assign in sequence DiveMode = TransitToStation
}
aggregate setStation {
run in sequence
break if (
SinkOffSurface
or not OnStation
)
assign in sequence DiveMode = StationKeep
}
aggregate setSink {
run in sequence
break if ( not SinkOffSurface )
assign in sequence DiveMode = SurfaceSink
}
}
call id="StartingMission" priorityHere=false refId="SurfaceComms"
aggregate DiveCmd {
run when ( called )
aggregate SurfaceSink {
run while ( DiveMode == SurfaceSink )
aggregate sink {
run in sequence
behavior Guidance:Buoyancy id="BuoyancyHold" {
run in parallel
set position = NaN cc
}
behavior Guidance:SetSpeed {
run in parallel
set speed = 0 m/s
}
syslog info "Sinking to " + SinkDepth~meter
behavior Guidance:Pitch {
run in sequence
timeout duration=SinkDepthTimeout {
syslog important "Timed out while trying to reach target sink depth at current depth of "
+ Universal:depth~meter
}
set depth = SinkDepth
}
# Once we've sunk, either resume transit or station keeping depths and speeds
aggregate sinkToTransit {
run in sequence
break if ( OnStation )
assign in sequence DiveMode = TransitToStation
}
aggregate sinkToStation {
run in sequence
break if ( not OnStation )
assign in sequence DiveMode = StationKeep
}
}
}
aggregate TransitToStation {
run while ( DiveMode == TransitToStation )
aggregate dive {
run in sequence
behavior Guidance:Buoyancy {
run in parallel
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch {
run in parallel
set depth = ApproachDepth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
}
}
aggregate StationKeep {
run while ( DiveMode == StationKeep )
assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:KeepStation {
run in parallel
set latitude = Latitude
set longitude = Longitude
set radius = Radius
set speed = Speed
}
}
}
aggregate TransitToStation {
"""
Need a separate aggregate for transit if we want to specify a
different depth for the approach.
"""
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
}
assign in sequence DiveMode = StationKeep
assign in sequence OnStation = true
}
call id="OnStation" priorityHere=false refId="NeedComms"
aggregate KeepStation {
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Wait {
"""
Due to the way the KeepStation behavior is currently
implemented, we must run it in parallel and use a Wait to keep
from completing the mission before the timeout expires. However,
this new version of the mission uses a top-level timeout so that
the entire mission will not run for longer then MissionTimeout.
"""
run in sequence
set duration = MissionTimeout
}
}
}
2025-07-18T13:39:15.814Z,1752845955.814 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-07-18T13:39:23.636Z,1752845963.636 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree
2025-07-18T13:39:23.636Z,1752845963.636 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2025-07-18T13:39:23.637Z,1752845963.637 [CommandExec](IMPORTANT): got command set keepstation.Speed 0.9 meter_per_second
2025-07-18T13:39:23.638Z,1752845963.638 [CommandExec](IMPORTANT): got command run
2025-07-18T13:39:23.640Z,1752845963.640 [CommandExec](IMPORTANT): Running
2025-07-18T13:39:23.953Z,1752845963.953 [Default] Stopped
2025-07-18T13:39:23.953Z,1752845963.953 [Default](DEBUG): Aggregate::uninitialize Default
2025-07-18T13:39:23.954Z,1752845963.954 [Default:B.GoToSurface] Stopped
2025-07-18T13:39:23.954Z,1752845963.954 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T13:39:23.954Z,1752845963.954 [Default:CheckIn] Stopped
2025-07-18T13:39:23.954Z,1752845963.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-18T13:39:23.954Z,1752845963.954 [Default:CheckIn:C.Wait] Stopped
2025-07-18T13:39:23.954Z,1752845963.954 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T13:39:23.954Z,1752845963.954 [MissionManager](IMPORTANT): Started mission keepstation
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation] Running Loop=1
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation:StandardEnvelopes] Running Loop=1
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-07-18T13:39:23.955Z,1752845963.955 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:BackseatDriver] Running Loop=1
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:PowerOnly] Running Loop=1
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:PowerOnly:A] Running Loop=1
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:PowerOnly:B] Running Loop=1
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:PowerOnly:C] Running Loop=1
2025-07-18T13:39:23.956Z,1752845963.956 [keepstation:PowerOnly:D] Running Loop=1
2025-07-18T13:39:23.957Z,1752845963.957 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-07-18T13:39:23.957Z,1752845963.957 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-07-18T13:39:23.957Z,1752845963.957 [keepstation:B] Running Loop=1
2025-07-18T13:39:23.958Z,1752845963.958 [keepstation:PowerOnly] Running Loop=1
2025-07-18T13:39:23.958Z,1752845963.958 [keepstation:PowerOnly:D] Stopped
2025-07-18T13:39:23.958Z,1752845963.958 [keepstation:PowerOnly:C] Stopped
2025-07-18T13:39:23.958Z,1752845963.958 [keepstation:PowerOnly:B] Stopped
2025-07-18T13:39:23.958Z,1752845963.958 [keepstation:PowerOnly:A] Stopped
2025-07-18T13:39:23.958Z,1752845963.958 [keepstation:BackseatDriver] Running Loop=1
2025-07-18T13:39:23.959Z,1752845963.959 [keepstation:StandardEnvelopes] Running Loop=1
2025-07-18T13:39:23.959Z,1752845963.959 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-07-18T13:39:23.959Z,1752845963.959 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-07-18T13:39:23.960Z,1752845963.960 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-07-18T13:39:23.960Z,1752845963.960 [keepstation:B] Stopped
2025-07-18T13:39:23.961Z,1752845963.961 [keepstation:D] Running Loop=1
2025-07-18T13:39:24.367Z,1752845964.367 [keepstation:D] Stopped
2025-07-18T13:39:24.367Z,1752845964.367 [keepstation:E] Running Loop=1
2025-07-18T13:39:24.751Z,1752845964.751 [keepstation:E] Stopped
2025-07-18T13:39:24.752Z,1752845964.752 [keepstation:StartingMission] Running Loop=1
2025-07-18T13:39:24.752Z,1752845964.752 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-07-18T13:39:25.157Z,1752845965.157 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T13:39:25.157Z,1752845965.157 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T13:39:25.157Z,1752845965.157 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T13:39:25.158Z,1752845965.158 [keepstation:SurfaceComms:A] Stopped
2025-07-18T13:39:25.158Z,1752845965.158 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T13:39:25.158Z,1752845965.158 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T13:39:25.564Z,1752845965.564 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-07-18T13:39:25.564Z,1752845965.564 [keepstation:NeedComms] Running Loop=1
2025-07-18T13:39:25.565Z,1752845965.565 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T13:39:25.565Z,1752845965.565 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T13:39:25.565Z,1752845965.565 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T13:39:25.565Z,1752845965.565 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T13:39:25.565Z,1752845965.565 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T13:39:25.566Z,1752845965.566 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T13:39:25.566Z,1752845965.566 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T13:39:25.567Z,1752845965.567 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T13:39:25.567Z,1752845965.567 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T13:39:25.567Z,1752845965.567 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T13:39:25.569Z,1752845965.569 [keepstation:NeedComms:A](INFO): last time_fix was: 1752845548.000000 second since 1970/01/01T00:00:00Z
2025-07-18T13:39:25.569Z,1752845965.569 [keepstation:NeedComms:A] Stopped
2025-07-18T13:39:25.980Z,1752845965.980 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T13:39:26.363Z,1752845966.363 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-18T13:39:27.969Z,1752845967.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133926.00,A,3647.90557,N,12151.06200,W,0.039,265.01,180725,,,D*7C
2025-07-18T13:39:27.971Z,1752845967.971 [NAL9602](INFO): GPS fix at 20250718T133926: (36.798426, -121.851033)
2025-07-18T13:39:27.983Z,1752845967.983 [keepstation:NeedComms:C] Stopped
2025-07-18T13:39:27.983Z,1752845967.983 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T13:39:28.405Z,1752845968.405 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-18T13:39:35.758Z,1752845975.758 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250718T132925/Courier0016.lzma
2025-07-18T13:39:40.093Z,1752845980.093 [NAL9602](INFO): SBD MO Status=1, MOMSN=10179, MT Status=0, MTMSN=0
2025-07-18T13:39:40.093Z,1752845980.093 [NAL9602](INFO): No messages in MT queue
2025-07-18T13:39:46.800Z,1752845986.800 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Courier0016.lzma.bak
2025-07-18T13:39:46.800Z,1752845986.800 [DataOverHttps](INFO): SBD MOMSN=25545103
2025-07-18T13:40:10.800Z,1752846010.800 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T13:40:13.252Z,1752846013.252 [DataOverHttps](INFO): Sending 437 bytes from file Logs/20250718T132925/Express0017.lzma
2025-07-18T13:40:24.293Z,1752846024.293 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0017.lzma.bak
2025-07-18T13:40:24.293Z,1752846024.293 [DataOverHttps](INFO): SBD MOMSN=25545106
2025-07-18T13:40:54.410Z,1752846054.410 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250718T132925/Express0020.lzma
2025-07-18T13:41:05.452Z,1752846065.452 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0020.lzma.bak
2025-07-18T13:41:05.452Z,1752846065.452 [DataOverHttps](INFO): SBD MOMSN=25545123
2025-07-18T13:41:07.030Z,1752846067.030 [keepstation:NeedComms:D] Stopped
2025-07-18T13:41:07.031Z,1752846067.031 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T13:41:07.445Z,1752846067.445 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-18T13:41:09.041Z,1752846069.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134108.00,A,3647.90422,N,12151.06399,W,0.583,281.90,180725,,,D*7B
2025-07-18T13:41:09.043Z,1752846069.043 [NAL9602](INFO): GPS fix at 20250718T134108: (36.798404, -121.851067)
2025-07-18T13:41:09.088Z,1752846069.088 [keepstation:NeedComms:E] Stopped
2025-07-18T13:41:09.089Z,1752846069.089 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T13:41:09.089Z,1752846069.089 [keepstation:NeedComms] Stopped
2025-07-18T13:41:09.089Z,1752846069.089 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T13:41:09.089Z,1752846069.089 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T13:41:09.089Z,1752846069.089 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T13:41:09.486Z,1752846069.486 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T13:41:09.487Z,1752846069.487 [keepstation:SurfaceComms:B] Stopped
2025-07-18T13:41:09.487Z,1752846069.487 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T13:41:09.487Z,1752846069.487 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T13:41:09.487Z,1752846069.487 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T13:41:09.487Z,1752846069.487 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T13:41:09.874Z,1752846069.874 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T13:41:09.874Z,1752846069.874 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-07-18T13:41:09.874Z,1752846069.874 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T13:41:09.874Z,1752846069.874 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T13:41:09.874Z,1752846069.874 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T13:41:09.875Z,1752846069.875 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T13:41:09.875Z,1752846069.875 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T13:41:10.286Z,1752846070.286 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T13:41:10.294Z,1752846070.294 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T13:41:10.294Z,1752846070.294 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T13:41:10.294Z,1752846070.294 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T13:41:10.294Z,1752846070.294 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T13:41:10.295Z,1752846070.295 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T13:41:10.695Z,1752846070.695 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T13:41:10.696Z,1752846070.696 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T13:41:10.696Z,1752846070.696 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T13:41:10.696Z,1752846070.696 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T13:41:10.696Z,1752846070.696 [keepstation:SurfaceComms] Stopped
2025-07-18T13:41:10.696Z,1752846070.696 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:StartingMission] Stopped
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:TransitToStation] Running Loop=1
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:TransitToStation:Dive] Running Loop=1
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-07-18T13:41:11.109Z,1752846071.109 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-07-18T13:41:11.110Z,1752846071.110 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-07-18T13:41:11.110Z,1752846071.110 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-07-18T13:41:11.115Z,1752846071.115 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-07-18T13:41:11.491Z,1752846071.491 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-07-18T13:41:11.492Z,1752846071.492 [keepstation:TransitToStation:Dive] Running Loop=1
2025-07-18T13:41:11.492Z,1752846071.492 [keepstation:DiveCmd] Running Loop=1
2025-07-18T13:41:11.492Z,1752846071.492 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-07-18T13:41:11.492Z,1752846071.492 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-07-18T13:41:11.492Z,1752846071.492 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-07-18T13:41:11.492Z,1752846071.492 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-07-18T13:41:11.493Z,1752846071.493 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-07-18T13:41:11.494Z,1752846071.494 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-07-18T13:41:11.494Z,1752846071.494 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-07-18T13:41:11.494Z,1752846071.494 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-07-18T13:41:11.494Z,1752846071.494 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-07-18T13:41:11.498Z,1752846071.498 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-07-18T13:41:11.498Z,1752846071.498 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-07-18T13:41:11.499Z,1752846071.499 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T13:41:11.499Z,1752846071.499 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T13:41:11.499Z,1752846071.499 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T13:41:11.499Z,1752846071.499 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T13:41:11.499Z,1752846071.499 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T13:41:11.499Z,1752846071.499 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T13:41:11.499Z,1752846071.499 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-07-18T13:41:11.500Z,1752846071.500 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-07-18T13:41:11.501Z,1752846071.501 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-07-18T13:41:11.501Z,1752846071.501 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-07-18T13:41:11.502Z,1752846071.502 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-07-18T13:41:11.502Z,1752846071.502 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-07-18T13:41:11.502Z,1752846071.502 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-07-18T13:41:11.526Z,1752846071.526 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-07-18T13:41:11.526Z,1752846071.526 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-07-18T13:41:11.526Z,1752846071.526 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-07-18T13:41:11.526Z,1752846071.526 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-07-18T13:41:11.526Z,1752846071.526 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-07-18T13:41:11.527Z,1752846071.527 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-07-18T13:41:11.551Z,1752846071.551 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-07-18T13:41:11.552Z,1752846071.552 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-07-18T13:41:11.552Z,1752846071.552 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-07-18T13:41:48.634Z,1752846108.634 [NAL9602](INFO): SBD MO Status=1, MOMSN=10180, MT Status=0, MTMSN=0
2025-07-18T13:41:48.634Z,1752846108.634 [NAL9602](INFO): No messages in MT queue
2025-07-18T13:42:19.329Z,1752846139.329 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T13:42:47.790Z,1752846167.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:43:00.843Z,1752846180.843 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243416
2025-07-18T13:43:10.751Z,1752846190.751 [Radio_Surface](INFO): Powering down
2025-07-18T13:43:10.882Z,1752846190.882 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T13:45:20.792Z,1752846320.792 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-07-18T13:45:20.792Z,1752846320.792 [ElevatorServo] No Fault, FailCount= 3
2025-07-18T13:45:21.230Z,1752846321.230 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2025-07-18T13:45:21.230Z,1752846321.230 [ElevatorServo](FAULT): Elevator failed to initialize
2025-07-18T13:45:21.230Z,1752846321.230 [ElevatorServo] Communications Fault, FailCount= 1
2025-07-18T13:45:21.230Z,1752846321.230 [ElevatorServo](ERROR): Communications Fault
2025-07-18T13:45:21.273Z,1752846321.273 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2025-07-18T13:45:21.551Z,1752846321.551 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:45:21.551Z,1752846321.551 [ElevatorServo](INFO): Powering down
2025-07-18T13:45:22.361Z,1752846322.361 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:45:22.479Z,1752846322.479 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:45:22.538Z,1752846322.538 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-07-18T13:45:22.538Z,1752846322.538 [ElevatorServo] No Fault, FailCount= 1
2025-07-18T13:45:27.662Z,1752846327.662 [ElevatorServo](FAULT): Overload Error
2025-07-18T13:45:27.662Z,1752846327.662 [ElevatorServo] Hardware Fault, FailCount= 2
2025-07-18T13:45:27.663Z,1752846327.663 [ElevatorServo](ERROR): Hardware Fault
2025-07-18T13:45:27.865Z,1752846327.865 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-07-18T13:45:28.039Z,1752846328.039 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:45:28.039Z,1752846328.039 [ElevatorServo](INFO): Powering down
2025-07-18T13:45:28.863Z,1752846328.863 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:45:28.983Z,1752846328.983 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:45:29.014Z,1752846329.014 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-07-18T13:45:29.014Z,1752846329.014 [ElevatorServo] No Fault, FailCount= 2
2025-07-18T13:45:47.514Z,1752846347.514 [ElevatorServo](INFO): Failure count cleared after critical for ElevatorServo
2025-07-18T13:46:17.194Z,1752846377.194 [ElevatorServo](FAULT): Overload Error
2025-07-18T13:46:17.194Z,1752846377.194 [ElevatorServo] Hardware Fault, FailCount= 1
2025-07-18T13:46:17.194Z,1752846377.194 [ElevatorServo](ERROR): Hardware Fault
2025-07-18T13:46:17.394Z,1752846377.394 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-07-18T13:46:17.469Z,1752846377.469 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:46:17.469Z,1752846377.469 [ElevatorServo](INFO): Powering down
2025-07-18T13:46:18.257Z,1752846378.257 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:46:18.371Z,1752846378.371 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:46:18.717Z,1752846378.717 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-07-18T13:46:18.718Z,1752846378.718 [ElevatorServo] No Fault, FailCount= 1
2025-07-18T13:46:23.298Z,1752846383.298 [ElevatorServo](FAULT): Overload Error
2025-07-18T13:46:23.298Z,1752846383.298 [ElevatorServo] Hardware Fault, FailCount= 2
2025-07-18T13:46:23.298Z,1752846383.298 [ElevatorServo](ERROR): Hardware Fault
2025-07-18T13:46:23.585Z,1752846383.585 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-07-18T13:46:23.675Z,1752846383.675 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:46:23.675Z,1752846383.675 [ElevatorServo](INFO): Powering down
2025-07-18T13:46:24.503Z,1752846384.503 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:46:24.619Z,1752846384.619 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:46:24.861Z,1752846384.861 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-07-18T13:46:24.862Z,1752846384.862 [ElevatorServo] No Fault, FailCount= 2
2025-07-18T13:46:29.802Z,1752846389.802 [ElevatorServo](FAULT): Overload Error
2025-07-18T13:46:29.802Z,1752846389.802 [ElevatorServo] Hardware Fault, FailCount= 3
2025-07-18T13:46:29.802Z,1752846389.802 [ElevatorServo](ERROR): Hardware Fault
2025-07-18T13:46:29.961Z,1752846389.961 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-07-18T13:46:29.962Z,1752846389.962 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2025-07-18T13:46:30.184Z,1752846390.184 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:46:30.184Z,1752846390.184 [ElevatorServo](INFO): Powering down
2025-07-18T13:46:30.227Z,1752846390.227 [CBIT](INFO): Critical error at 20250718T134629
2025-07-18T13:46:30.228Z,1752846390.228 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-07-18T13:46:30.557Z,1752846390.557 [MissionManager](INFO): MissionManager is completed.
2025-07-18T13:46:30.557Z,1752846390.557 [MissionManager](INFO): Uninitializing Mission keepstation
2025-07-18T13:46:30.557Z,1752846390.557 [keepstation] Stopped
2025-07-18T13:46:30.557Z,1752846390.557 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-07-18T13:46:30.557Z,1752846390.557 [keepstation:StandardEnvelopes] Stopped
2025-07-18T13:46:30.558Z,1752846390.558 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-07-18T13:46:30.558Z,1752846390.558 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-07-18T13:46:30.558Z,1752846390.558 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-07-18T13:46:30.558Z,1752846390.558 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-07-18T13:46:30.558Z,1752846390.558 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:BackseatDriver] Stopped
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:PowerOnly] Stopped
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:PowerOnly:E.Wait] Stopped
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:DiveCmd] Stopped
2025-07-18T13:46:30.559Z,1752846390.559 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation] Stopped
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:TransitToStation] Stopped
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:TransitToStation:Dive] Stopped
2025-07-18T13:46:30.560Z,1752846390.560 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-07-18T13:46:30.561Z,1752846390.561 [keepstation:TransitToStation:Dive:A] Stopped
2025-07-18T13:46:30.561Z,1752846390.561 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-07-18T13:46:30.561Z,1752846390.561 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-07-18T13:46:30.561Z,1752846390.561 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-07-18T13:46:31.013Z,1752846391.013 [MissionManager](IMPORTANT): Started mission Default
2025-07-18T13:46:31.013Z,1752846391.013 [Default] Running Loop=1
2025-07-18T13:46:31.014Z,1752846391.014 [Default](DEBUG): Aggregate::initialize Default
2025-07-18T13:46:31.014Z,1752846391.014 [Default:B.GoToSurface] Running Loop=1
2025-07-18T13:46:31.014Z,1752846391.014 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T13:46:31.014Z,1752846391.014 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-18T13:46:31.014Z,1752846391.014 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-18T13:46:31.015Z,1752846391.015 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-18T13:46:31.015Z,1752846391.015 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-18T13:46:31.015Z,1752846391.015 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T13:46:31.016Z,1752846391.016 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T13:46:31.016Z,1752846391.016 [Default:A.Wait] Running Loop=1
2025-07-18T13:46:31.016Z,1752846391.016 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-18T13:46:31.024Z,1752846391.024 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T13:46:31.144Z,1752846391.144 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T13:46:31.554Z,1752846391.554 [Radio_Surface](INFO): Powering up
2025-07-18T13:46:37.727Z,1752846397.727 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T13:46:44.069Z,1752846404.069 [Default:A.Wait](INFO): Done Waiting.
2025-07-18T13:46:44.069Z,1752846404.069 [Default:A.Wait] Stopped
2025-07-18T13:46:44.069Z,1752846404.069 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T13:47:07.850Z,1752846427.850 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:47:22.857Z,1752846442.857 [Default:CheckIn] Running Loop=1
2025-07-18T13:47:22.857Z,1752846442.857 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-18T13:47:22.857Z,1752846442.857 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-18T13:47:42.990Z,1752846462.990 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:47:58.389Z,1752846478.389 [NAL9602](INFO): SBD MO Status=2, MOMSN=10181, MT Status=2, MTMSN=0
2025-07-18T13:47:58.389Z,1752846478.389 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-18T13:48:18.134Z,1752846498.134 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:48:53.274Z,1752846533.274 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:49:28.422Z,1752846568.422 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:50:04.048Z,1752846604.048 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-18T13:50:26.551Z,1752846626.551 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003948
2025-07-18T13:52:08.906Z,1752846728.906 [NAL9602](INFO): SBD MO Status=2, MOMSN=10181, MT Status=2, MTMSN=0
2025-07-18T13:52:08.907Z,1752846728.907 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-18T13:52:20.675Z,1752846740.675 [CommandExec](IMPORTANT): got command show stack
2025-07-18T13:52:20.676Z,1752846740.676 [CommandExec](IMPORTANT): Behavior Stack:
2025-07-18T13:52:20.676Z,1752846740.676 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2025-07-18T13:52:20.676Z,1752846740.676 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2025-07-18T13:52:23.085Z,1752846743.085 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-18T13:47:22.9Z
2025-07-18T13:52:23.085Z,1752846743.085 [Default:CheckIn:Read_GPS] Stopped
2025-07-18T13:52:23.086Z,1752846743.086 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-18T13:52:27.528Z,1752846747.528 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T13:52:34.267Z,1752846754.267 [CommandExec](IMPORTANT): got command get ElevatorServo.loadAtStartup
2025-07-18T13:52:34.267Z,1752846754.267 [CommandExec](IMPORTANT): ElevatorServo.loadAtStartup 1 bool
2025-07-18T13:52:36.570Z,1752846756.570 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20250718T132925/Courier0022.lzma
2025-07-18T13:52:47.612Z,1752846767.612 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Courier0022.lzma.bak
2025-07-18T13:52:47.612Z,1752846767.612 [DataOverHttps](INFO): SBD MOMSN=25545145
2025-07-18T13:52:58.269Z,1752846778.269 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T13:53:13.551Z,1752846793.551 [DataOverHttps](INFO): Sending 563 bytes from file Logs/20250718T132925/Express0023.lzma
2025-07-18T13:53:24.592Z,1752846804.592 [DataOverHttps](INFO): Moved sent file to Logs/20250718T132925/Express0023.lzma.bak
2025-07-18T13:53:24.592Z,1752846804.592 [DataOverHttps](INFO): SBD MOMSN=25545149
2025-07-18T13:53:26.160Z,1752846806.160 [Default:CheckIn:Read_Iridium] Stopped
2025-07-18T13:53:26.160Z,1752846806.160 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-18T13:53:26.160Z,1752846806.160 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-18T13:53:32.147Z,1752846812.147 [CommandExec](IMPORTANT): got command configSet ElevatorServo.loadAtStartup 0 bool persist
2025-07-18T13:53:32.148Z,1752846812.148 [CommandExec](IMPORTANT): configSet ElevatorServo.loadAtStartup requires a restart to take effect.
2025-07-18T13:53:42.283Z,1752846822.283 [CommandExec](IMPORTANT): got command restart application
2025-07-18T13:53:43.286Z,1752846823.286 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:43.286Z,1752846823.286 [CommandExec](INFO): Uninitializing the command executive.
2025-07-18T13:53:43.287Z,1752846823.287 [CommandExec](INFO): Uninitializing the command scheduler.
2025-07-18T13:53:43.287Z,1752846823.287 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:43.438Z,1752846823.438 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-07-18T13:53:43.438Z,1752846823.438 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-07-18T13:53:43.438Z,1752846823.438 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:43.439Z,1752846823.439 [NavChartDb](INFO): Join timeout helper Thread ID is 1718
2025-07-18T13:53:43.602Z,1752846823.602 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:43.603Z,1752846823.603 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:43.610Z,1752846823.610 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-07-18T13:53:43.610Z,1752846823.610 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:43.611Z,1752846823.611 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1719
2025-07-18T13:53:43.827Z,1752846823.827 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:43.827Z,1752846823.827 [WetLabsBB2FL](INFO): Powering down
2025-07-18T13:53:43.828Z,1752846823.828 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:43.830Z,1752846823.830 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-07-18T13:53:43.831Z,1752846823.831 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:43.831Z,1752846823.831 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1720
2025-07-18T13:53:44.770Z,1752846824.770 [CTD_Seabird](INFO): Powering down
2025-07-18T13:53:44.782Z,1752846824.782 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:44.798Z,1752846824.798 [CTD_Seabird](INFO): Powering down
2025-07-18T13:53:44.810Z,1752846824.810 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:44.818Z,1752846824.818 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-07-18T13:53:44.818Z,1752846824.818 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:44.819Z,1752846824.819 [Radio_Surface](INFO): Join timeout helper Thread ID is 1721
2025-07-18T13:53:45.218Z,1752846825.218 [Radio_Surface](INFO): Powering down
2025-07-18T13:53:45.219Z,1752846825.219 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:45.219Z,1752846825.219 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:45.234Z,1752846825.234 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-07-18T13:53:45.234Z,1752846825.234 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:45.234Z,1752846825.234 [Onboard](INFO): Join timeout helper Thread ID is 1722
2025-07-18T13:53:45.278Z,1752846825.278 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T13:53:49.918Z,1752846829.918 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:49.918Z,1752846829.918 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:49.935Z,1752846829.935 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-07-18T13:53:49.935Z,1752846829.935 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:49.935Z,1752846829.935 [DataOverHttps](INFO): Join timeout helper Thread ID is 1723
2025-07-18T13:53:50.298Z,1752846830.298 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:50.299Z,1752846830.299 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.311Z,1752846830.311 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-07-18T13:53:50.311Z,1752846830.311 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.311Z,1752846830.311 [DAT](INFO): Join timeout helper Thread ID is 1724
2025-07-18T13:53:50.430Z,1752846830.430 [DAT](INFO): Powering down
2025-07-18T13:53:50.503Z,1752846830.503 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:50.503Z,1752846830.503 [DAT](INFO): Powering down
2025-07-18T13:53:50.504Z,1752846830.504 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.515Z,1752846830.515 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-07-18T13:53:50.515Z,1752846830.515 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.515Z,1752846830.515 [BackseatComponent](INFO): Join timeout helper Thread ID is 1725
2025-07-18T13:53:50.598Z,1752846830.598 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:50.599Z,1752846830.599 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.619Z,1752846830.619 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-07-18T13:53:50.619Z,1752846830.619 [logger ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.619Z,1752846830.619 [logger](INFO): Join timeout helper Thread ID is 1726
2025-07-18T13:53:50.630Z,1752846830.630 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:50.631Z,1752846830.631 [logger ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.639Z,1752846830.639 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-07-18T13:53:50.639Z,1752846830.639 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.639Z,1752846830.639 [CommandLine](INFO): Join timeout helper Thread ID is 1727
2025-07-18T13:53:50.670Z,1752846830.670 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:50.671Z,1752846830.671 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.679Z,1752846830.679 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-07-18T13:53:50.679Z,1752846830.679 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.679Z,1752846830.679 [CommandExec](INFO): Join timeout helper Thread ID is 1728
2025-07-18T13:53:50.680Z,1752846830.680 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-07-18T13:53:50.681Z,1752846830.681 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:50.681Z,1752846830.681 [controlThread](INFO): Join timeout helper Thread ID is 1729
2025-07-18T13:53:50.766Z,1752846830.766 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T13:53:50.767Z,1752846830.767 [controlThread](DEBUG): Uninitializing ControlThread
2025-07-18T13:53:50.767Z,1752846830.767 [AHRS_M2](INFO): Powering down
2025-07-18T13:53:50.847Z,1752846830.847 [NAL9602](INFO): Powering down
2025-07-18T13:53:50.848Z,1752846830.848 [Sonardyne_Nano](INFO): Powering down
2025-07-18T13:53:51.054Z,1752846831.054 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-07-18T13:53:51.055Z,1752846831.055 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-07-18T13:53:51.056Z,1752846831.056 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-07-18T13:53:51.056Z,1752846831.056 [MissionManager](INFO): Uninitializing Mission Default
2025-07-18T13:53:51.056Z,1752846831.056 [Default] Stopped
2025-07-18T13:53:51.056Z,1752846831.056 [Default](DEBUG): Aggregate::uninitialize Default
2025-07-18T13:53:51.056Z,1752846831.056 [Default:B.GoToSurface] Stopped
2025-07-18T13:53:51.057Z,1752846831.057 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T13:53:51.057Z,1752846831.057 [Default:CheckIn] Stopped
2025-07-18T13:53:51.057Z,1752846831.057 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-18T13:53:51.057Z,1752846831.057 [Default:CheckIn:C.Wait] Stopped
2025-07-18T13:53:51.057Z,1752846831.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T13:53:51.060Z,1752846831.060 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-07-18T13:53:51.060Z,1752846831.060 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-07-18T13:53:51.060Z,1752846831.060 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-07-18T13:53:51.061Z,1752846831.061 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-07-18T13:53:51.061Z,1752846831.061 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-07-18T13:53:51.061Z,1752846831.061 [BuoyancyServo](INFO): Powering down
2025-07-18T13:53:51.074Z,1752846831.074 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T13:53:51.074Z,1752846831.074 [ElevatorServo](INFO): Powering down
2025-07-18T13:53:51.075Z,1752846831.075 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-07-18T13:53:51.075Z,1752846831.075 [MassServo](INFO): Powering down
2025-07-18T13:53:51.076Z,1752846831.076 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-07-18T13:53:51.076Z,1752846831.076 [RudderServo](INFO): Powering down
2025-07-18T13:53:51.077Z,1752846831.077 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-07-18T13:53:51.077Z,1752846831.077 [ThrusterHE](INFO): Powering down
2025-07-18T13:53:51.078Z,1752846831.078 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-07-18T13:53:51.078Z,1752846831.078 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-07-18T13:53:51.079Z,1752846831.079 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-07-18T13:53:51.079Z,1752846831.079 [CBIT](DEBUG): Powering off loads.
2025-07-18T13:53:51.090Z,1752846831.090 [CBIT](DEBUG): Disabling WDT.
2025-07-18T13:53:51.102Z,1752846831.102 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-07-18T13:53:51.102Z,1752846831.102 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-07-18T13:53:51.103Z,1752846831.103 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.280Z,1752846831.280 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.283Z,1752846831.283 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.289Z,1752846831.289 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.296Z,1752846831.296 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.339Z,1752846831.339 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.383Z,1752846831.383 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.387Z,1752846831.387 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.408Z,1752846831.408 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-07-18T13:53:51.408Z,1752846831.408 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-07-18T13:53:51.424Z,1752846831.424 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-18T13:53:51.475Z,1752846831.475 [logger ThreadHandler](INFO): Thread cancelled.