2025-09-30T22:54:20.859Z,1759272860.859 [Supervisor](DEBUG): Initializing supervisor.
2025-09-30T22:54:20.864Z,1759272860.864 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-09-30T22:54:20.865Z,1759272860.865 [SyncHandler](INFO): Protected caller Thread ID is 916
2025-09-30T22:54:20.865Z,1759272860.865 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-09-30T22:54:20.867Z,1759272860.867 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-09-30T22:54:20.867Z,1759272860.867 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 917
2025-09-30T22:54:20.871Z,1759272860.871 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-09-30T22:54:20.891Z,1759272860.891 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-09-30T22:54:20.892Z,1759272860.892 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-09-30T22:54:20.892Z,1759272860.892 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 918
2025-09-30T22:54:20.897Z,1759272860.897 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-09-30T22:54:20.898Z,1759272860.898 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-09-30T22:54:20.898Z,1759272860.898 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 919
2025-09-30T22:54:20.900Z,1759272860.900 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-09-30T22:54:20.901Z,1759272860.901 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-09-30T22:54:20.902Z,1759272860.902 [logger ThreadHandler](INFO): Protected caller Thread ID is 920
2025-09-30T22:54:20.906Z,1759272860.906 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-09-30T22:54:20.906Z,1759272860.906 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-09-30T22:54:20.908Z,1759272860.908 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-09-30T22:54:21.014Z,1759272861.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-09-30T22:54:21.014Z,1759272861.014 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-09-30T22:54:21.251Z,1759272861.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-09-30T22:54:21.252Z,1759272861.252 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-09-30T22:54:21.522Z,1759272861.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-09-30T22:54:21.522Z,1759272861.522 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-09-30T22:54:21.639Z,1759272861.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-09-30T22:54:21.639Z,1759272861.639 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-09-30T22:54:22.395Z,1759272862.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-09-30T22:54:22.396Z,1759272862.396 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-09-30T22:54:22.943Z,1759272862.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-09-30T22:54:22.943Z,1759272862.943 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-09-30T22:54:23.484Z,1759272863.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-09-30T22:54:23.484Z,1759272863.484 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-09-30T22:54:23.919Z,1759272863.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-09-30T22:54:23.919Z,1759272863.919 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-09-30T22:54:24.273Z,1759272864.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-09-30T22:54:24.274Z,1759272864.274 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-09-30T22:54:24.667Z,1759272864.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-09-30T22:54:24.668Z,1759272864.668 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-09-30T22:54:24.903Z,1759272864.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-09-30T22:54:24.904Z,1759272864.904 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-09-30T22:54:25.059Z,1759272865.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-09-30T22:54:25.060Z,1759272865.060 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-09-30T22:54:25.180Z,1759272865.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-09-30T22:54:25.181Z,1759272865.181 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-09-30T22:54:25.456Z,1759272865.456 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-09-30T22:54:25.907Z,1759272865.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-09-30T22:54:25.907Z,1759272865.907 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-09-30T22:54:26.370Z,1759272866.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-09-30T22:54:26.372Z,1759272866.372 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-09-30T22:54:26.373Z,1759272866.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-09-30T22:54:26.468Z,1759272866.468 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-09-30T22:54:26.773Z,1759272866.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-09-30T22:54:26.774Z,1759272866.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-09-30T22:54:26.876Z,1759272866.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-09-30T22:54:27.070Z,1759272867.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-09-30T22:54:27.182Z,1759272867.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-09-30T22:54:27.301Z,1759272867.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-09-30T22:54:27.423Z,1759272867.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-09-30T22:54:27.526Z,1759272867.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-09-30T22:54:27.693Z,1759272867.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-09-30T22:54:27.800Z,1759272867.800 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-09-30T22:54:27.895Z,1759272867.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-09-30T22:54:28.003Z,1759272868.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-09-30T22:54:28.100Z,1759272868.100 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-09-30T22:54:28.109Z,1759272868.109 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-09-30T22:54:28.288Z,1759272868.288 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-09-30T22:54:28.288Z,1759272868.288 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-09-30T22:54:28.456Z,1759272868.456 [BuoyancyServo] Loaded
2025-09-30T22:54:28.456Z,1759272868.456 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-09-30T22:54:28.480Z,1759272868.480 [ElevatorServo] Loaded
2025-09-30T22:54:28.480Z,1759272868.480 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-09-30T22:54:28.502Z,1759272868.502 [MassServo] Loaded
2025-09-30T22:54:28.503Z,1759272868.503 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-09-30T22:54:28.525Z,1759272868.525 [RudderServo] Loaded
2025-09-30T22:54:28.525Z,1759272868.525 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-09-30T22:54:28.543Z,1759272868.543 [ThrusterHE] Loaded
2025-09-30T22:54:28.543Z,1759272868.543 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-09-30T22:54:28.543Z,1759272868.543 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-09-30T22:54:28.544Z,1759272868.544 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-09-30T22:54:28.709Z,1759272868.709 [SBIT](DEBUG): Construct Startup Built In Test.
2025-09-30T22:54:28.724Z,1759272868.724 [SBIT] Loaded
2025-09-30T22:54:28.724Z,1759272868.724 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-09-30T22:54:28.727Z,1759272868.727 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-09-30T22:54:28.747Z,1759272868.747 [IBIT] Loaded
2025-09-30T22:54:28.748Z,1759272868.748 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-09-30T22:54:28.753Z,1759272868.753 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-09-30T22:54:28.951Z,1759272868.951 [CBIT] Loaded
2025-09-30T22:54:28.951Z,1759272868.951 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-09-30T22:54:28.982Z,1759272868.982 [GFScanner] Loaded
2025-09-30T22:54:28.983Z,1759272868.983 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-09-30T22:54:28.983Z,1759272868.983 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-09-30T22:54:28.983Z,1759272868.983 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-09-30T22:54:29.154Z,1759272869.154 [CTD_Seabird] Loaded
2025-09-30T22:54:29.154Z,1759272869.154 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-09-30T22:54:29.155Z,1759272869.155 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0
2025-09-30T22:54:29.156Z,1759272869.156 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1008
2025-09-30T22:54:29.191Z,1759272869.191 [ESPComponent] Loaded
2025-09-30T22:54:29.191Z,1759272869.191 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-09-30T22:54:29.213Z,1759272869.213 [PAR_Licor] Loaded
2025-09-30T22:54:29.213Z,1759272869.213 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-09-30T22:54:29.214Z,1759272869.214 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-09-30T22:54:29.215Z,1759272869.215 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-09-30T22:54:29.266Z,1759272869.266 [DepthRateCalculator] Loaded
2025-09-30T22:54:29.267Z,1759272869.267 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-09-30T22:54:29.272Z,1759272869.272 [PitchRateCalculator] Loaded
2025-09-30T22:54:29.272Z,1759272869.272 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-09-30T22:54:29.282Z,1759272869.282 [SpeedCalculator] Loaded
2025-09-30T22:54:29.283Z,1759272869.283 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-09-30T22:54:29.287Z,1759272869.287 [YawRateCalculator] Loaded
2025-09-30T22:54:29.288Z,1759272869.288 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-09-30T22:54:29.307Z,1759272869.307 [ElevatorOffsetCalculator] Loaded
2025-09-30T22:54:29.307Z,1759272869.307 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-09-30T22:54:29.308Z,1759272869.308 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-09-30T22:54:29.309Z,1759272869.309 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-09-30T22:54:29.444Z,1759272869.444 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-09-30T22:54:29.445Z,1759272869.445 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-09-30T22:54:29.474Z,1759272869.474 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-09-30T22:54:29.475Z,1759272869.475 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-09-30T22:54:29.588Z,1759272869.588 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-09-30T22:54:29.588Z,1759272869.588 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-09-30T22:54:29.602Z,1759272869.602 [NavChart] Loaded
2025-09-30T22:54:29.603Z,1759272869.603 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-09-30T22:54:29.608Z,1759272869.608 [UniversalFixResidualReporter] Loaded
2025-09-30T22:54:29.608Z,1759272869.608 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-09-30T22:54:29.609Z,1759272869.609 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-09-30T22:54:29.610Z,1759272869.610 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-09-30T22:54:30.093Z,1759272870.093 [AHRS_M2] Loaded
2025-09-30T22:54:30.093Z,1759272870.093 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-09-30T22:54:30.132Z,1759272870.132 [BackseatComponent] Loaded
2025-09-30T22:54:30.132Z,1759272870.132 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-09-30T22:54:30.133Z,1759272870.133 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0
2025-09-30T22:54:30.133Z,1759272870.133 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1009
2025-09-30T22:54:30.136Z,1759272870.136 [LcmUniversalReporter] Loaded
2025-09-30T22:54:30.137Z,1759272870.137 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-09-30T22:54:31.314Z,1759272871.314 [BPC1] Loaded
2025-09-30T22:54:31.314Z,1759272871.314 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-09-30T22:54:31.417Z,1759272871.417 [DAT] Loaded
2025-09-30T22:54:31.417Z,1759272871.417 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-09-30T22:54:31.418Z,1759272871.418 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2025-09-30T22:54:31.418Z,1759272871.418 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1010
2025-09-30T22:54:31.494Z,1759272871.494 [DataOverHttps] Loaded
2025-09-30T22:54:31.494Z,1759272871.494 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-09-30T22:54:31.495Z,1759272871.495 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0
2025-09-30T22:54:31.495Z,1759272871.495 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1011
2025-09-30T22:54:31.519Z,1759272871.519 [Depth_Keller] Loaded
2025-09-30T22:54:31.519Z,1759272871.519 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-09-30T22:54:31.524Z,1759272871.524 [DropWeight] Loaded
2025-09-30T22:54:31.524Z,1759272871.524 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-09-30T22:54:31.587Z,1759272871.587 [NAL9602] Loaded
2025-09-30T22:54:31.588Z,1759272871.588 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-09-30T22:54:31.614Z,1759272871.614 [Onboard] Loaded
2025-09-30T22:54:31.614Z,1759272871.614 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-09-30T22:54:31.615Z,1759272871.615 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0
2025-09-30T22:54:31.615Z,1759272871.615 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1012
2025-09-30T22:54:31.621Z,1759272871.621 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-09-30T22:54:31.635Z,1759272871.635 [PowerOnly] Loaded
2025-09-30T22:54:31.635Z,1759272871.635 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-09-30T22:54:31.649Z,1759272871.649 [Power24vConverter] Loaded
2025-09-30T22:54:31.650Z,1759272871.650 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-09-30T22:54:31.666Z,1759272871.666 [Radio_Surface] Loaded
2025-09-30T22:54:31.666Z,1759272871.666 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-09-30T22:54:31.667Z,1759272871.667 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0
2025-09-30T22:54:31.668Z,1759272871.668 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1013
2025-09-30T22:54:31.683Z,1759272871.683 [Sonardyne_Nano] Loaded
2025-09-30T22:54:31.684Z,1759272871.684 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-09-30T22:54:31.684Z,1759272871.684 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-09-30T22:54:31.685Z,1759272871.685 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-09-30T22:54:31.745Z,1759272871.745 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-09-30T22:54:31.746Z,1759272871.746 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-09-30T22:54:31.800Z,1759272871.800 [VerticalControl](DEBUG): Construct VerticalControl.
2025-09-30T22:54:31.851Z,1759272871.851 [VerticalControl] Loaded
2025-09-30T22:54:31.852Z,1759272871.852 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-09-30T22:54:31.855Z,1759272871.855 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-09-30T22:54:31.898Z,1759272871.898 [HorizontalControl] Loaded
2025-09-30T22:54:31.899Z,1759272871.899 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-09-30T22:54:31.901Z,1759272871.901 [SpeedControl](DEBUG): Construct SpeedControl.
2025-09-30T22:54:31.903Z,1759272871.903 [SpeedControl] Loaded
2025-09-30T22:54:31.903Z,1759272871.903 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-09-30T22:54:31.906Z,1759272871.906 [LoopControl](DEBUG): Construct LoopControl.
2025-09-30T22:54:31.907Z,1759272871.907 [LoopControl] Loaded
2025-09-30T22:54:31.907Z,1759272871.907 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-09-30T22:54:31.907Z,1759272871.907 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-09-30T22:54:31.908Z,1759272871.908 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-09-30T22:54:31.918Z,1759272871.918 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-09-30T22:54:31.919Z,1759272871.919 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-09-30T22:54:32.267Z,1759272872.267 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-09-30T22:54:32.274Z,1759272872.274 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-09-30T22:54:32.277Z,1759272872.277 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-09-30T22:54:32.288Z,1759272872.288 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-09-30T22:54:32.289Z,1759272872.289 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-09-30T22:54:32.290Z,1759272872.290 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1014
2025-09-30T22:54:32.294Z,1759272872.294 [Supervisor](INFO): Main Thread ID is 478
2025-09-30T22:54:32.294Z,1759272872.294 [Supervisor](DEBUG): Running supervisor.
2025-09-30T22:54:32.295Z,1759272872.295 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1015
2025-09-30T22:54:32.295Z,1759272872.295 [CommandExec](INFO): Initializing the command executive.
2025-09-30T22:54:32.297Z,1759272872.297 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1016
2025-09-30T22:54:32.299Z,1759272872.299 [controlThread ThreadHandler](INFO): Handler Thread ID is 1017
2025-09-30T22:54:32.299Z,1759272872.299 [controlThread](DEBUG): Initializing ControlThread
2025-09-30T22:54:32.301Z,1759272872.301 [SBIT](INFO): Initialize SBIT Component.
2025-09-30T22:54:32.302Z,1759272872.302 [SBIT](IMPORTANT): git: 2025-09-29-1-g40a8d3ac0
2025-09-30T22:54:32.302Z,1759272872.302 [SBIT](INFO): git hash: 40a8d3ac0b5b7c3327208f41095fc66196e8acd5
2025-09-30T22:54:32.302Z,1759272872.302 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-09-30T22:54:32.304Z,1759272872.304 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-09-30T22:54:32.305Z,1759272872.305 [SBIT](INFO): Beginning SBIT in 143.000000 seconds.
2025-09-30T22:54:32.305Z,1759272872.305 [IBIT](INFO): Initialize IBIT Component.
2025-09-30T22:54:32.306Z,1759272872.306 [CBIT](DEBUG): Initialize CBIT Component.
2025-09-30T22:54:32.307Z,1759272872.307 [logger ThreadHandler](INFO): Handler Thread ID is 1018
2025-09-30T22:54:32.318Z,1759272872.318 [CBIT](DEBUG): Initialized mux pins.
2025-09-30T22:54:32.318Z,1759272872.318 [CBIT](DEBUG): Initializing the watchdog timer.
2025-09-30T22:54:32.331Z,1759272872.331 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1019
2025-09-30T22:54:32.331Z,1759272872.331 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-09-30T22:54:32.336Z,1759272872.336 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1021
2025-09-30T22:54:32.342Z,1759272872.342 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-09-30T22:54:32.343Z,1759272872.343 [CBIT](DEBUG): Initializing heartbeat.
2025-09-30T22:54:32.344Z,1759272872.344 [DAT ThreadHandler](INFO): Handler Thread ID is 1022
2025-09-30T22:54:32.344Z,1759272872.344 [DAT](INFO): Powering up
2025-09-30T22:54:32.345Z,1759272872.345 [DAT](DEBUG): Initializing DAT.
2025-09-30T22:54:32.348Z,1759272872.348 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1024
2025-09-30T22:54:32.349Z,1759272872.349 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-09-30T22:54:32.371Z,1759272872.371 [Onboard ThreadHandler](INFO): Handler Thread ID is 1025
2025-09-30T22:54:32.388Z,1759272872.388 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1026
2025-09-30T22:54:32.411Z,1759272872.411 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1027
2025-09-30T22:54:32.414Z,1759272872.414 [CBIT](DEBUG): Deactivating emergency mode.
2025-09-30T22:54:32.417Z,1759272872.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-09-30T22:54:32.417Z,1759272872.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-09-30T22:54:32.417Z,1759272872.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-09-30T22:54:32.417Z,1759272872.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-09-30T22:54:32.417Z,1759272872.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-09-30T22:54:32.418Z,1759272872.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-09-30T22:54:32.418Z,1759272872.418 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-09-30T22:54:32.418Z,1759272872.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-09-30T22:54:32.454Z,1759272872.454 [CBIT](DEBUG): Backplane powered.
2025-09-30T22:54:32.455Z,1759272872.455 [GFScanner](DEBUG): Initializing GFScanner
2025-09-30T22:54:32.455Z,1759272872.455 [GFScanner](DEBUG): Deactivating GF circuits.
2025-09-30T22:54:32.463Z,1759272872.463 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-09-30T22:54:32.463Z,1759272872.463 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-09-30T22:54:32.463Z,1759272872.463 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-09-30T22:54:32.464Z,1759272872.464 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-09-30T22:54:32.464Z,1759272872.464 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-09-30T22:54:32.465Z,1759272872.465 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-09-30T22:54:32.466Z,1759272872.466 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-09-30T22:54:32.469Z,1759272872.469 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-09-30T22:54:32.475Z,1759272872.475 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-09-30T22:54:32.476Z,1759272872.476 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-09-30T22:54:32.476Z,1759272872.476 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-09-30T22:54:32.477Z,1759272872.477 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-09-30T22:54:32.478Z,1759272872.478 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-09-30T22:54:32.478Z,1759272872.478 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-09-30T22:54:32.487Z,1759272872.487 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-09-30T22:54:32.564Z,1759272872.564 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-09-30T22:54:32.569Z,1759272872.569 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-30T22:54:32.618Z,1759272872.618 [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-09-30T22:54:32.619Z,1759272872.619 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-09-30T22:54:32.619Z,1759272872.619 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-09-30T22:54:32.620Z,1759272872.620 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-09-30T22:54:32.794Z,1759272872.794 [Radio_Surface](INFO): Powering up
2025-09-30T22:54:32.907Z,1759272872.907 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-09-30T22:54:32.929Z,1759272872.929 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-09-30T22:54:32.930Z,1759272872.930 [Default:A.Wait](DEBUG): Construct Wait.
2025-09-30T22:54:32.932Z,1759272872.932 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-30T22:54:32.999Z,1759272872.999 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-09-30T22:54:33.002Z,1759272873.002 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-09-30T22:54:33.024Z,1759272873.024 [Default:E.Execute](DEBUG): Construct Execute.
2025-09-30T22:54:33.027Z,1759272873.027 [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-09-30T22:54:33.032Z,1759272873.032 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,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-09-30T22:54:33.058Z,1759272873.058 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-30T22:54:33.127Z,1759272873.127 [Depth_Keller](INFO): Initializing.
2025-09-30T22:54:33.130Z,1759272873.130 [Power24vConverter](INFO): Powering up.
2025-09-30T22:54:33.130Z,1759272873.130 [Sonardyne_Nano](INFO): Initializing.
2025-09-30T22:54:33.159Z,1759272873.159 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-09-30T22:54:33.181Z,1759272873.181 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-09-30T22:54:33.187Z,1759272873.187 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-09-30T22:54:33.188Z,1759272873.188 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-09-30T22:54:33.195Z,1759272873.195 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-09-30T22:54:33.196Z,1759272873.196 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-30T22:54:33.203Z,1759272873.203 [MassServo](DEBUG): Initializing MassServo.
2025-09-30T22:54:33.203Z,1759272873.203 [RudderServo](DEBUG): Initializing EZServoServo.
2025-09-30T22:54:33.211Z,1759272873.211 [RudderServo](DEBUG): Initializing RudderServo.
2025-09-30T22:54:33.211Z,1759272873.211 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-09-30T22:54:33.219Z,1759272873.219 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-09-30T22:54:33.474Z,1759272873.474 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-09-30T22:54:33.478Z,1759272873.478 [DropWeight] Hardware Fault, FailCount= 1
2025-09-30T22:54:33.478Z,1759272873.478 [DropWeight](ERROR): Hardware Fault
2025-09-30T22:54:33.479Z,1759272873.479 [PowerOnly](INFO): Powering up loadControl
2025-09-30T22:54:33.523Z,1759272873.523 [CommandExec](FAULT): Scheduling is paused
2025-09-30T22:54:33.524Z,1759272873.524 [CBIT](INFO): Critical error at 20250930T225433
2025-09-30T22:54:33.524Z,1759272873.524 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-09-30T22:54:33.535Z,1759272873.535 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-30T22:54:33.535Z,1759272873.535 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-30T22:54:33.868Z,1759272873.868 [CBIT](INFO): Critical error at 20250930T225433
2025-09-30T22:54:35.065Z,1759272875.065 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-09-30T22:54:36.353Z,1759272876.353 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-09-30T22:54:38.935Z,1759272878.935 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-09-30T22:54:44.439Z,1759272884.439 [DAT](INFO): DAT read: 

2025-09-30T22:54:44.441Z,1759272884.441 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-09-30T22:54:46.456Z,1759272886.456 [DAT](INFO): DAT read: MF Frequency Band

2025-09-30T22:54:46.457Z,1759272886.457 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-09-30T22:54:46.457Z,1759272886.457 [DAT](INFO): DAT read: Sep 30 2025 22:54:40 

2025-09-30T22:54:47.464Z,1759272887.464 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-09-30T22:54:47.465Z,1759272887.465 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-09-30T22:54:47.465Z,1759272887.465 [DAT](INFO): commRate: 1200
2025-09-30T22:54:49.530Z,1759272889.530 [DAT](INFO): entering command mode
2025-09-30T22:54:49.731Z,1759272889.731 [DAT](INFO): DAT read: 

2025-09-30T22:54:49.732Z,1759272889.732 [DAT](INFO): DAT read: user:1>

2025-09-30T22:54:49.732Z,1759272889.732 [DAT](INFO): setting verbose to 3
2025-09-30T22:54:49.983Z,1759272889.983 [DAT](INFO): DAT read: user:1>

2025-09-30T22:54:49.984Z,1759272889.984 [DAT](INFO): DAT read: Verbose         | 3           

2025-09-30T22:54:49.985Z,1759272889.985 [DAT](INFO): set verbose to 3
2025-09-30T22:54:49.985Z,1759272889.985 [DAT](INFO): setting DatVerbose to 27440
2025-09-30T22:54:50.235Z,1759272890.235 [DAT](INFO): DAT read: user:2>

2025-09-30T22:54:50.236Z,1759272890.236 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-09-30T22:54:50.236Z,1759272890.236 [DAT](INFO): set DatVerbose to 27440
2025-09-30T22:54:50.237Z,1759272890.237 [DAT](INFO): setting transmit power to 8
2025-09-30T22:54:50.487Z,1759272890.487 [DAT](INFO): DAT read: user:3>

2025-09-30T22:54:50.489Z,1759272890.489 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-09-30T22:54:50.489Z,1759272890.489 [DAT](INFO): set transmit power to 8
2025-09-30T22:54:50.489Z,1759272890.489 [DAT](INFO): setting local address to 5
2025-09-30T22:54:50.740Z,1759272890.740 [DAT](INFO): DAT read: user:4>

2025-09-30T22:54:50.741Z,1759272890.741 [DAT](INFO): DAT read: LocalAddr       | 5           

2025-09-30T22:54:50.741Z,1759272890.741 [DAT](INFO): set local address to 5
2025-09-30T22:54:50.742Z,1759272890.742 [DAT](INFO): Setting time to: 22:54:50 And date to:9/30/2025
2025-09-30T22:54:50.991Z,1759272890.991 [DAT](INFO): DAT read: user:5>

2025-09-30T22:54:50.992Z,1759272890.992 [DAT](INFO): DAT read: Tue Sep 30, 2025  22:54:50  

2025-09-30T22:54:50.993Z,1759272890.993 [DAT](INFO): Local DAT time set to Tue Sep 30, 2025  22:54:50  

2025-09-30T22:55:01.748Z,1759272901.748 [NAL9602](INFO): Powering up NAL9602
2025-09-30T22:55:08.636Z,1759272908.636 [CommandExec](IMPORTANT): got command strobe off
2025-09-30T22:55:08.637Z,1759272908.637 [CommandExec](IMPORTANT): Deactivating strobe
2025-09-30T22:55:12.645Z,1759272912.645 [NAL9602](INFO): NAL9602 initialized
2025-09-30T22:55:15.400Z,1759272915.400 [CommandExec](IMPORTANT): got command strobe off
2025-09-30T22:55:15.400Z,1759272915.400 [CommandExec](IMPORTANT): Deactivating strobe
2025-09-30T22:56:44.117Z,1759273004.117 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004956
2025-09-30T22:56:56.107Z,1759273016.107 [SBIT](IMPORTANT): Beginning Startup BIT
2025-09-30T22:56:56.112Z,1759273016.112 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-30T22:57:00.620Z,1759273020.620 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008303
CHAN A1 (24V): 0.003426
CHAN A2 (12V): -0.004793
CHAN A3 (5V): -0.003941
CHAN B0 (3.3V): -0.001816
CHAN B1 (3.15aV): -0.001679
CHAN B2 (3.15bV): -0.001714
CHAN B3 (GND): -0.000759
OPEN: 0.007174
Full Scale: +/- 1 mA
2025-09-30T22:57:32.556Z,1759273052.556 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-09-30T22:57:32.556Z,1759273052.556 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-30T22:57:32.708Z,1759273052.708 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-30T22:57:33.083Z,1759273053.083 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-30T22:57:33.083Z,1759273053.083 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-09-30T22:57:49.901Z,1759273069.901 [SBIT](IMPORTANT): SBIT PASSED
2025-09-30T22:57:49.902Z,1759273069.902 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;

2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool;

2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;

2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;

2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=319.798725 cubic_centimeter;

2025-09-30T22:57:49.903Z,1759273069.903 [SBIT](IMPORTANT): VerticalControl.massDefault=30.833858 millimeter;

2025-09-30T22:57:49.904Z,1759273069.904 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2025-09-30T22:57:50.299Z,1759273070.299 [MissionManager](IMPORTANT): Started mission Startup
2025-09-30T22:57:50.299Z,1759273070.299 [Startup] Running Loop=1
2025-09-30T22:57:50.300Z,1759273070.300 [Startup](DEBUG): Aggregate::initialize Startup
2025-09-30T22:57:50.300Z,1759273070.300 [Startup:A.GoToSurface] Running Loop=1
2025-09-30T22:57:50.300Z,1759273070.300 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-30T22:57:50.300Z,1759273070.300 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-30T22:57:50.301Z,1759273070.301 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-30T22:57:50.301Z,1759273070.301 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-30T22:57:50.301Z,1759273070.301 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-30T22:57:50.302Z,1759273070.302 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-30T22:57:50.302Z,1759273070.302 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-30T22:57:50.323Z,1759273070.323 [Startup:StartupSatComms] Running Loop=1
2025-09-30T22:57:50.323Z,1759273070.323 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-09-30T22:57:50.323Z,1759273070.323 [Startup:StartupSatComms:A] Running Loop=1
2025-09-30T22:57:50.700Z,1759273070.700 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-09-30T22:58:48.784Z,1759273128.784 [CommandExec](IMPORTANT): got command restart application
2025-09-30T22:58:49.787Z,1759273129.787 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:49.787Z,1759273129.787 [CommandExec](INFO): Uninitializing the command executive.
2025-09-30T22:58:49.787Z,1759273129.787 [CommandExec](INFO): Uninitializing the command scheduler.
2025-09-30T22:58:49.787Z,1759273129.787 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:49.938Z,1759273129.938 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-09-30T22:58:49.938Z,1759273129.938 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-09-30T22:58:49.939Z,1759273129.939 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:49.939Z,1759273129.939 [NavChartDb](INFO): Join timeout helper Thread ID is 1042
2025-09-30T22:58:50.067Z,1759273130.067 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:50.067Z,1759273130.067 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:50.081Z,1759273130.081 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-09-30T22:58:50.081Z,1759273130.081 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:50.081Z,1759273130.081 [Radio_Surface](INFO): Join timeout helper Thread ID is 1043
2025-09-30T22:58:50.179Z,1759273130.179 [Radio_Surface](INFO): Powering down
2025-09-30T22:58:50.179Z,1759273130.179 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:50.180Z,1759273130.180 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:50.191Z,1759273130.191 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-09-30T22:58:50.191Z,1759273130.191 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:50.191Z,1759273130.191 [Onboard](INFO): Join timeout helper Thread ID is 1044
2025-09-30T22:58:50.283Z,1759273130.283 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-30T22:58:50.511Z,1759273130.511 [Startup:StartupSatComms:A](INFO): Timed out from 2025-09-30T22:57:50.3Z
2025-09-30T22:58:50.511Z,1759273130.511 [Startup:StartupSatComms:A] Stopped
2025-09-30T22:58:50.511Z,1759273130.511 [Startup:StartupSatComms:B] Running Loop=1
2025-09-30T22:58:50.913Z,1759273130.913 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-30T22:58:52.591Z,1759273132.591 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:52.591Z,1759273132.591 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:52.610Z,1759273132.610 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-09-30T22:58:52.610Z,1759273132.610 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:52.611Z,1759273132.611 [DataOverHttps](INFO): Join timeout helper Thread ID is 1045
2025-09-30T22:58:53.295Z,1759273133.295 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:53.295Z,1759273133.295 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:53.302Z,1759273133.302 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-09-30T22:58:53.303Z,1759273133.303 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:53.303Z,1759273133.303 [DAT](INFO): Join timeout helper Thread ID is 1046
2025-09-30T22:58:53.328Z,1759273133.328 [DAT](INFO): Powering down
2025-09-30T22:58:53.399Z,1759273133.399 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:53.399Z,1759273133.399 [DAT](INFO): Powering down
2025-09-30T22:58:53.400Z,1759273133.400 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:53.415Z,1759273133.415 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-09-30T22:58:53.415Z,1759273133.415 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:53.416Z,1759273133.416 [BackseatComponent](INFO): Join timeout helper Thread ID is 1047
2025-09-30T22:58:53.479Z,1759273133.479 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:53.479Z,1759273133.479 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:53.495Z,1759273133.495 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-09-30T22:58:53.495Z,1759273133.495 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:53.496Z,1759273133.496 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1048
2025-09-30T22:58:54.383Z,1759273134.383 [CTD_Seabird](INFO): Powering down
2025-09-30T22:58:54.394Z,1759273134.394 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:54.395Z,1759273134.395 [CTD_Seabird](INFO): Powering down
2025-09-30T22:58:54.411Z,1759273134.411 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:54.423Z,1759273134.423 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-09-30T22:58:54.423Z,1759273134.423 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:54.424Z,1759273134.424 [logger](INFO): Join timeout helper Thread ID is 1049
2025-09-30T22:58:54.446Z,1759273134.446 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:54.446Z,1759273134.446 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:54.463Z,1759273134.463 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-09-30T22:58:54.463Z,1759273134.463 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:54.464Z,1759273134.464 [CommandLine](INFO): Join timeout helper Thread ID is 1050
2025-09-30T22:58:54.539Z,1759273134.539 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:54.539Z,1759273134.539 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:54.556Z,1759273134.556 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-09-30T22:58:54.556Z,1759273134.556 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:54.556Z,1759273134.556 [CommandExec](INFO): Join timeout helper Thread ID is 1051
2025-09-30T22:58:54.557Z,1759273134.557 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-09-30T22:58:54.557Z,1759273134.557 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:54.558Z,1759273134.558 [controlThread](INFO): Join timeout helper Thread ID is 1052
2025-09-30T22:58:54.907Z,1759273134.907 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-30T22:58:54.907Z,1759273134.907 [controlThread](DEBUG): Uninitializing ControlThread
2025-09-30T22:58:54.907Z,1759273134.907 [AHRS_M2](INFO): Powering down
2025-09-30T22:58:54.987Z,1759273134.987 [NAL9602](INFO): Powering down
2025-09-30T22:58:54.988Z,1759273134.988 [Sonardyne_Nano](INFO): Powering down
2025-09-30T22:58:55.195Z,1759273135.195 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-09-30T22:58:55.195Z,1759273135.195 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-09-30T22:58:55.196Z,1759273135.196 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-09-30T22:58:55.196Z,1759273135.196 [MissionManager](INFO): Uninitializing Mission Default
2025-09-30T22:58:55.199Z,1759273135.199 [MissionManager](INFO): Uninitializing Mission Startup
2025-09-30T22:58:55.199Z,1759273135.199 [Startup] Stopped
2025-09-30T22:58:55.199Z,1759273135.199 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-09-30T22:58:55.199Z,1759273135.199 [Startup:A.GoToSurface] Stopped
2025-09-30T22:58:55.200Z,1759273135.200 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-30T22:58:55.200Z,1759273135.200 [Startup:StartupSatComms] Stopped
2025-09-30T22:58:55.200Z,1759273135.200 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-09-30T22:58:55.200Z,1759273135.200 [Startup:StartupSatComms:B] Stopped
2025-09-30T22:58:55.201Z,1759273135.201 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-09-30T22:58:55.202Z,1759273135.202 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-09-30T22:58:55.202Z,1759273135.202 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-09-30T22:58:55.202Z,1759273135.202 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-09-30T22:58:55.203Z,1759273135.203 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-09-30T22:58:55.203Z,1759273135.203 [BuoyancyServo](INFO): Powering down
2025-09-30T22:58:55.215Z,1759273135.215 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-09-30T22:58:55.215Z,1759273135.215 [ElevatorServo](INFO): Powering down
2025-09-30T22:58:55.216Z,1759273135.216 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-30T22:58:55.216Z,1759273135.216 [MassServo](INFO): Powering down
2025-09-30T22:58:55.216Z,1759273135.216 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-09-30T22:58:55.217Z,1759273135.217 [RudderServo](INFO): Powering down
2025-09-30T22:58:55.217Z,1759273135.217 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-09-30T22:58:55.217Z,1759273135.217 [ThrusterHE](INFO): Powering down
2025-09-30T22:58:55.218Z,1759273135.218 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-09-30T22:58:55.219Z,1759273135.219 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-09-30T22:58:55.219Z,1759273135.219 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-09-30T22:58:55.219Z,1759273135.219 [CBIT](DEBUG): Powering off loads.
2025-09-30T22:58:55.230Z,1759273135.230 [CBIT](DEBUG): Disabling WDT.
2025-09-30T22:58:55.243Z,1759273135.243 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-30T22:58:55.243Z,1759273135.243 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-30T22:58:55.244Z,1759273135.244 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.272Z,1759273135.272 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-30T22:58:55.272Z,1759273135.272 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-30T22:58:55.290Z,1759273135.290 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.322Z,1759273135.322 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.325Z,1759273135.325 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.331Z,1759273135.331 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.337Z,1759273135.337 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.378Z,1759273135.378 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.432Z,1759273135.432 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-30T22:58:55.495Z,1759273135.495 [logger ThreadHandler](INFO): Thread cancelled.