2025-05-12T18:06:22.833Z,1747073182.833 [Supervisor](DEBUG): Initializing supervisor.
2025-05-12T18:06:22.837Z,1747073182.837 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-12T18:06:22.838Z,1747073182.838 [SyncHandler](INFO): Protected caller Thread ID is 831
2025-05-12T18:06:22.838Z,1747073182.838 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-12T18:06:22.839Z,1747073182.839 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-12T18:06:22.840Z,1747073182.840 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832
2025-05-12T18:06:22.844Z,1747073182.844 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-12T18:06:22.864Z,1747073182.864 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-12T18:06:22.865Z,1747073182.865 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-12T18:06:22.865Z,1747073182.865 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833
2025-05-12T18:06:22.870Z,1747073182.870 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-12T18:06:22.871Z,1747073182.871 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-12T18:06:22.871Z,1747073182.871 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834
2025-05-12T18:06:22.873Z,1747073182.873 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-12T18:06:22.874Z,1747073182.874 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-12T18:06:22.875Z,1747073182.875 [logger ThreadHandler](INFO): Protected caller Thread ID is 835
2025-05-12T18:06:22.879Z,1747073182.879 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-12T18:06:22.879Z,1747073182.879 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-12T18:06:22.885Z,1747073182.885 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-12T18:06:22.994Z,1747073182.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-12T18:06:22.996Z,1747073182.996 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-12T18:06:23.241Z,1747073183.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-12T18:06:23.243Z,1747073183.243 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-12T18:06:23.537Z,1747073183.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-12T18:06:23.539Z,1747073183.539 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-12T18:06:23.659Z,1747073183.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-12T18:06:23.661Z,1747073183.661 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-12T18:06:24.474Z,1747073184.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-12T18:06:24.475Z,1747073184.475 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-12T18:06:25.024Z,1747073185.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-12T18:06:25.025Z,1747073185.025 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-12T18:06:26.084Z,1747073186.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-12T18:06:26.085Z,1747073186.085 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-12T18:06:26.560Z,1747073186.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-12T18:06:26.562Z,1747073186.562 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-12T18:06:26.945Z,1747073186.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-12T18:06:26.947Z,1747073186.947 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-12T18:06:27.999Z,1747073187.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-12T18:06:27.000Z,1747073188.000 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-12T18:06:28.246Z,1747073188.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-12T18:06:28.248Z,1747073188.248 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-12T18:06:28.404Z,1747073188.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-12T18:06:28.405Z,1747073188.405 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-12T18:06:28.498Z,1747073188.498 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-12T18:06:28.590Z,1747073188.590 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-12T18:06:28.798Z,1747073188.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-12T18:06:28.799Z,1747073188.799 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-12T18:06:29.070Z,1747073189.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-12T18:06:29.071Z,1747073189.071 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-05-12T18:06:29.075Z,1747073189.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-05-12T18:06:29.171Z,1747073189.171 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-05-12T18:06:29.459Z,1747073189.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-12T18:06:29.460Z,1747073189.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-05-12T18:06:29.557Z,1747073189.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-05-12T18:06:29.744Z,1747073189.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-05-12T18:06:29.858Z,1747073189.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-05-12T18:06:29.980Z,1747073189.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-05-12T18:06:30.106Z,1747073190.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-05-12T18:06:30.213Z,1747073190.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-05-12T18:06:30.391Z,1747073190.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-05-12T18:06:30.499Z,1747073190.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-05-12T18:06:30.596Z,1747073190.596 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-05-12T18:06:30.707Z,1747073190.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-05-12T18:06:30.805Z,1747073190.805 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-05-12T18:06:30.810Z,1747073190.810 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-12T18:06:31.045Z,1747073191.045 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-12T18:06:31.045Z,1747073191.045 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-12T18:06:31.711Z,1747073191.711 [BuoyancyServo] Loaded
2025-05-12T18:06:31.711Z,1747073191.711 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-12T18:06:31.745Z,1747073191.745 [ElevatorServo] Loaded
2025-05-12T18:06:31.746Z,1747073191.746 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-12T18:06:31.786Z,1747073191.786 [MassServo] Loaded
2025-05-12T18:06:31.786Z,1747073191.786 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-12T18:06:31.809Z,1747073191.809 [RudderServo] Loaded
2025-05-12T18:06:31.809Z,1747073191.809 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-12T18:06:31.828Z,1747073191.828 [ThrusterHE] Loaded
2025-05-12T18:06:31.829Z,1747073191.829 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-05-12T18:06:31.829Z,1747073191.829 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-12T18:06:31.830Z,1747073191.830 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-12T18:06:32.296Z,1747073192.296 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-12T18:06:32.312Z,1747073192.312 [SBIT] Loaded
2025-05-12T18:06:32.312Z,1747073192.312 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-12T18:06:32.315Z,1747073192.315 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-12T18:06:32.337Z,1747073192.337 [IBIT] Loaded
2025-05-12T18:06:32.337Z,1747073192.337 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-12T18:06:32.342Z,1747073192.342 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-12T18:06:32.542Z,1747073192.542 [CBIT] Loaded
2025-05-12T18:06:32.542Z,1747073192.542 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-12T18:06:32.575Z,1747073192.575 [GFScanner] Loaded
2025-05-12T18:06:32.575Z,1747073192.575 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-05-12T18:06:32.575Z,1747073192.575 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-12T18:06:32.576Z,1747073192.576 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-12T18:06:32.799Z,1747073192.799 [CTD_Seabird] Loaded
2025-05-12T18:06:32.799Z,1747073192.799 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-12T18:06:32.800Z,1747073192.800 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406834E0
2025-05-12T18:06:32.801Z,1747073192.801 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 923
2025-05-12T18:06:32.837Z,1747073192.837 [ESPComponent] Loaded
2025-05-12T18:06:32.837Z,1747073192.837 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-05-12T18:06:32.860Z,1747073192.860 [PAR_Licor] Loaded
2025-05-12T18:06:32.860Z,1747073192.860 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-05-12T18:06:32.908Z,1747073192.908 [WetLabsBB2FL] Loaded
2025-05-12T18:06:32.908Z,1747073192.908 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-05-12T18:06:32.909Z,1747073192.909 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B34E0
2025-05-12T18:06:32.910Z,1747073192.910 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 924
2025-05-12T18:06:32.911Z,1747073192.911 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-12T18:06:32.911Z,1747073192.911 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-12T18:06:32.986Z,1747073192.986 [DepthRateCalculator] Loaded
2025-05-12T18:06:32.986Z,1747073192.986 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-12T18:06:32.992Z,1747073192.992 [PitchRateCalculator] Loaded
2025-05-12T18:06:32.992Z,1747073192.992 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-12T18:06:33.003Z,1747073193.003 [SpeedCalculator] Loaded
2025-05-12T18:06:33.003Z,1747073193.003 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-12T18:06:33.008Z,1747073193.008 [YawRateCalculator] Loaded
2025-05-12T18:06:33.008Z,1747073193.008 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-12T18:06:33.029Z,1747073193.029 [ElevatorOffsetCalculator] Loaded
2025-05-12T18:06:33.029Z,1747073193.029 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-12T18:06:33.030Z,1747073193.030 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-12T18:06:33.030Z,1747073193.030 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-12T18:06:33.207Z,1747073193.207 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-12T18:06:33.208Z,1747073193.208 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-12T18:06:33.257Z,1747073193.257 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-12T18:06:33.258Z,1747073193.258 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-12T18:06:33.403Z,1747073193.403 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-12T18:06:33.403Z,1747073193.403 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-12T18:06:33.418Z,1747073193.418 [NavChart] Loaded
2025-05-12T18:06:33.418Z,1747073193.418 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-12T18:06:33.424Z,1747073193.424 [UniversalFixResidualReporter] Loaded
2025-05-12T18:06:33.425Z,1747073193.425 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-12T18:06:33.425Z,1747073193.425 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-12T18:06:33.426Z,1747073193.426 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-12T18:06:34.332Z,1747073194.332 [AHRS_M2] Loaded
2025-05-12T18:06:34.333Z,1747073194.333 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-12T18:06:34.586Z,1747073194.586 [BackseatComponent] Loaded
2025-05-12T18:06:34.587Z,1747073194.587 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-12T18:06:34.588Z,1747073194.588 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2025-05-12T18:06:34.588Z,1747073194.588 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 925
2025-05-12T18:06:34.591Z,1747073194.591 [LcmUniversalReporter] Loaded
2025-05-12T18:06:34.592Z,1747073194.592 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-12T18:06:35.347Z,1747073195.347 [BPC1] Loaded
2025-05-12T18:06:35.347Z,1747073195.347 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-12T18:06:35.450Z,1747073195.450 [DAT] Loaded
2025-05-12T18:06:35.450Z,1747073195.450 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-05-12T18:06:35.451Z,1747073195.451 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2025-05-12T18:06:35.451Z,1747073195.451 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926
2025-05-12T18:06:35.595Z,1747073195.595 [DataOverHttps] Loaded
2025-05-12T18:06:35.595Z,1747073195.595 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-12T18:06:35.596Z,1747073195.596 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2025-05-12T18:06:35.596Z,1747073195.596 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 927
2025-05-12T18:06:35.620Z,1747073195.620 [Depth_Keller] Loaded
2025-05-12T18:06:35.621Z,1747073195.621 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-05-12T18:06:35.626Z,1747073195.626 [DropWeight] Loaded
2025-05-12T18:06:35.626Z,1747073195.626 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-05-12T18:06:35.669Z,1747073195.669 [DVL_micro] Loaded
2025-05-12T18:06:35.669Z,1747073195.669 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-05-12T18:06:35.733Z,1747073195.733 [NAL9602] Loaded
2025-05-12T18:06:35.734Z,1747073195.734 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-12T18:06:35.761Z,1747073195.761 [Onboard] Loaded
2025-05-12T18:06:35.761Z,1747073195.761 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-12T18:06:35.762Z,1747073195.762 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2025-05-12T18:06:35.762Z,1747073195.762 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 928
2025-05-12T18:06:35.769Z,1747073195.769 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2025-05-12T18:06:35.783Z,1747073195.783 [PowerOnly] Loaded
2025-05-12T18:06:35.783Z,1747073195.783 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-05-12T18:06:35.798Z,1747073195.798 [Power24vConverter] Loaded
2025-05-12T18:06:35.798Z,1747073195.798 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-12T18:06:35.815Z,1747073195.815 [Radio_Surface] Loaded
2025-05-12T18:06:35.815Z,1747073195.815 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-12T18:06:35.816Z,1747073195.816 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0
2025-05-12T18:06:35.816Z,1747073195.816 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 929
2025-05-12T18:06:35.833Z,1747073195.833 [Sonardyne_Nano] Loaded
2025-05-12T18:06:35.833Z,1747073195.833 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-05-12T18:06:35.834Z,1747073195.834 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-12T18:06:35.835Z,1747073195.835 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-12T18:06:35.925Z,1747073195.925 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-12T18:06:35.925Z,1747073195.925 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-12T18:06:36.003Z,1747073196.003 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-12T18:06:36.057Z,1747073196.057 [VerticalControl] Loaded
2025-05-12T18:06:36.058Z,1747073196.058 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-12T18:06:36.061Z,1747073196.061 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-12T18:06:36.106Z,1747073196.106 [HorizontalControl] Loaded
2025-05-12T18:06:36.107Z,1747073196.107 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-12T18:06:36.109Z,1747073196.109 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-12T18:06:36.111Z,1747073196.111 [SpeedControl] Loaded
2025-05-12T18:06:36.111Z,1747073196.111 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-12T18:06:36.114Z,1747073196.114 [LoopControl](DEBUG): Construct LoopControl.
2025-05-12T18:06:36.115Z,1747073196.115 [LoopControl] Loaded
2025-05-12T18:06:36.115Z,1747073196.115 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-12T18:06:36.115Z,1747073196.115 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-12T18:06:36.116Z,1747073196.116 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-12T18:06:36.132Z,1747073196.132 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-12T18:06:36.133Z,1747073196.133 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-12T18:06:36.568Z,1747073196.568 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-12T18:06:36.575Z,1747073196.575 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-12T18:06:36.578Z,1747073196.578 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-12T18:06:36.590Z,1747073196.590 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-12T18:06:36.591Z,1747073196.591 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-05-12T18:06:36.591Z,1747073196.591 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2025-05-12T18:06:36.595Z,1747073196.595 [Supervisor](INFO): Main Thread ID is 823
2025-05-12T18:06:36.595Z,1747073196.595 [Supervisor](DEBUG): Running supervisor.
2025-05-12T18:06:36.596Z,1747073196.596 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2025-05-12T18:06:36.597Z,1747073196.597 [CommandExec](INFO): Initializing the command executive.
2025-05-12T18:06:36.598Z,1747073196.598 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2025-05-12T18:06:36.600Z,1747073196.600 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2025-05-12T18:06:36.601Z,1747073196.601 [controlThread](DEBUG): Initializing ControlThread
2025-05-12T18:06:36.603Z,1747073196.603 [SBIT](INFO): Initialize SBIT Component.
2025-05-12T18:06:36.603Z,1747073196.603 [SBIT](IMPORTANT): git: 2025-05-12_B
2025-05-12T18:06:36.603Z,1747073196.603 [SBIT](INFO): git hash: 7c5b1c07405cfe7ca09821eb029d166d25d65d57
2025-05-12T18:06:36.604Z,1747073196.604 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-12T18:06:36.605Z,1747073196.605 [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-05-12T18:06:36.606Z,1747073196.606 [SBIT](INFO): Beginning SBIT in 107.000000 seconds.
2025-05-12T18:06:36.607Z,1747073196.607 [IBIT](INFO): Initialize IBIT Component.
2025-05-12T18:06:36.607Z,1747073196.607 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-12T18:06:36.608Z,1747073196.608 [logger ThreadHandler](INFO): Handler Thread ID is 934
2025-05-12T18:06:36.620Z,1747073196.620 [CBIT](DEBUG): Initialized mux pins.
2025-05-12T18:06:36.621Z,1747073196.621 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-05-12T18:06:36.621Z,1747073196.621 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-12T18:06:36.633Z,1747073196.633 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 935
2025-05-12T18:06:36.634Z,1747073196.634 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-12T18:06:36.638Z,1747073196.638 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 937
2025-05-12T18:06:36.641Z,1747073196.641 [WetLabsBB2FL](INFO): Powering up
2025-05-12T18:06:36.642Z,1747073196.642 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 939
2025-05-12T18:06:36.645Z,1747073196.645 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-05-12T18:06:36.645Z,1747073196.645 [CBIT](DEBUG): Initializing heartbeat.
2025-05-12T18:06:36.660Z,1747073196.660 [DAT ThreadHandler](INFO): Handler Thread ID is 940
2025-05-12T18:06:36.661Z,1747073196.661 [DAT](INFO): Powering up
2025-05-12T18:06:36.661Z,1747073196.661 [DAT](DEBUG): Initializing DAT.
2025-05-12T18:06:36.666Z,1747073196.666 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 942
2025-05-12T18:06:36.667Z,1747073196.667 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-12T18:06:36.681Z,1747073196.681 [Onboard ThreadHandler](INFO): Handler Thread ID is 943
2025-05-12T18:06:36.698Z,1747073196.698 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 944
2025-05-12T18:06:36.716Z,1747073196.716 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-12T18:06:36.717Z,1747073196.717 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945
2025-05-12T18:06:36.725Z,1747073196.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-12T18:06:36.725Z,1747073196.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-12T18:06:36.726Z,1747073196.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-12T18:06:36.726Z,1747073196.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-12T18:06:36.726Z,1747073196.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-12T18:06:36.726Z,1747073196.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-12T18:06:36.726Z,1747073196.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-12T18:06:36.726Z,1747073196.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-12T18:06:36.757Z,1747073196.757 [CBIT](DEBUG): Backplane powered.
2025-05-12T18:06:36.757Z,1747073196.757 [GFScanner](DEBUG): Initializing GFScanner
2025-05-12T18:06:36.757Z,1747073196.757 [GFScanner](DEBUG): Deactivating GF circuits.
2025-05-12T18:06:36.773Z,1747073196.773 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-12T18:06:36.773Z,1747073196.773 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-12T18:06:36.774Z,1747073196.774 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-12T18:06:36.774Z,1747073196.774 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-12T18:06:36.774Z,1747073196.774 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-12T18:06:36.775Z,1747073196.775 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-12T18:06:36.776Z,1747073196.776 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-12T18:06:36.780Z,1747073196.780 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-12T18:06:36.785Z,1747073196.785 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-12T18:06:36.786Z,1747073196.786 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-12T18:06:36.787Z,1747073196.787 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-12T18:06:36.787Z,1747073196.787 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-12T18:06:36.788Z,1747073196.788 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-05-12T18:06:36.788Z,1747073196.788 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-05-12T18:06:36.801Z,1747073196.801 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-05-12T18:06:36.852Z,1747073196.852 [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-05-12T18:06:36.879Z,1747073196.879 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-12T18:06:36.936Z,1747073196.936 [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-05-12T18:06:36.936Z,1747073196.936 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-05-12T18:06:36.936Z,1747073196.936 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-05-12T18:06:36.942Z,1747073196.942 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-05-12T18:06:37.109Z,1747073197.109 [Radio_Surface](INFO): Powering up
2025-05-12T18:06:37.267Z,1747073197.267 [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-05-12T18:06:37.289Z,1747073197.289 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-12T18:06:37.290Z,1747073197.290 [Default:A.Wait](DEBUG): Construct Wait.
2025-05-12T18:06:37.292Z,1747073197.292 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-12T18:06:37.337Z,1747073197.337 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-05-12T18:06:37.340Z,1747073197.340 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-12T18:06:37.362Z,1747073197.362 [Default:E.Execute](DEBUG): Construct Execute.
2025-05-12T18:06:37.381Z,1747073197.381 [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-05-12T18:06:37.386Z,1747073197.386 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,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-05-12T18:06:37.406Z,1747073197.406 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-12T18:06:37.545Z,1747073197.545 [Depth_Keller](INFO): Initializing.
2025-05-12T18:06:37.554Z,1747073197.554 [PowerOnly](INFO): Powering up loadControl
2025-05-12T18:06:37.573Z,1747073197.573 [Power24vConverter](INFO): Powering up.
2025-05-12T18:06:37.574Z,1747073197.574 [Sonardyne_Nano](INFO): Initializing.
2025-05-12T18:06:37.617Z,1747073197.617 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-12T18:06:37.665Z,1747073197.665 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-12T18:06:37.673Z,1747073197.673 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-12T18:06:37.674Z,1747073197.674 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-12T18:06:37.681Z,1747073197.681 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-12T18:06:37.682Z,1747073197.682 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-12T18:06:37.689Z,1747073197.689 [MassServo](DEBUG): Initializing MassServo.
2025-05-12T18:06:37.690Z,1747073197.690 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-12T18:06:37.697Z,1747073197.697 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-12T18:06:37.698Z,1747073197.698 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-05-12T18:06:37.705Z,1747073197.705 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-05-12T18:06:39.253Z,1747073199.253 [WetLabsBB2FL](INFO): Powering down
2025-05-12T18:06:39.824Z,1747073199.824 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-05-12T18:06:41.075Z,1747073201.075 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-05-12T18:06:43.302Z,1747073203.302 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-05-12T18:06:43.665Z,1747073203.665 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-05-12T18:06:48.757Z,1747073208.757 [DAT](INFO): DAT read: 

2025-05-12T18:06:48.759Z,1747073208.759 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-05-12T18:06:50.522Z,1747073210.522 [DAT](INFO): DAT read: MF Frequency Band

2025-05-12T18:06:50.523Z,1747073210.523 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-05-12T18:06:50.523Z,1747073210.523 [DAT](INFO): DAT read: May 12 2025 18:07:04 

2025-05-12T18:06:51.782Z,1747073211.782 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-05-12T18:06:51.783Z,1747073211.783 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-05-12T18:06:51.783Z,1747073211.783 [DAT](INFO): commRate: 1200
2025-05-12T18:06:53.849Z,1747073213.849 [DAT](INFO): entering command mode
2025-05-12T18:06:54.049Z,1747073214.049 [DAT](INFO): DAT read: 

2025-05-12T18:06:54.050Z,1747073214.050 [DAT](INFO): DAT read: user:1>

2025-05-12T18:06:54.050Z,1747073214.050 [DAT](INFO): setting verbose to 3
2025-05-12T18:06:54.301Z,1747073214.301 [DAT](INFO): DAT read: user:1>

2025-05-12T18:06:54.302Z,1747073214.302 [DAT](INFO): DAT read: Verbose         | 3           

2025-05-12T18:06:54.303Z,1747073214.303 [DAT](INFO): set verbose to 3
2025-05-12T18:06:54.303Z,1747073214.303 [DAT](INFO): setting DatVerbose to 27440
2025-05-12T18:06:54.554Z,1747073214.554 [DAT](INFO): DAT read: user:2>

2025-05-12T18:06:54.555Z,1747073214.555 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-05-12T18:06:54.555Z,1747073214.555 [DAT](INFO): set DatVerbose to 27440
2025-05-12T18:06:54.555Z,1747073214.555 [DAT](INFO): setting transmit power to 8
2025-05-12T18:06:54.805Z,1747073214.805 [DAT](INFO): DAT read: user:3>

2025-05-12T18:06:54.806Z,1747073214.806 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-05-12T18:06:54.807Z,1747073214.807 [DAT](INFO): set transmit power to 8
2025-05-12T18:06:54.807Z,1747073214.807 [DAT](INFO): setting local address to 5
2025-05-12T18:06:55.057Z,1747073215.057 [DAT](INFO): DAT read: user:4>

2025-05-12T18:06:55.058Z,1747073215.058 [DAT](INFO): DAT read: LocalAddr       | 5           

2025-05-12T18:06:55.059Z,1747073215.059 [DAT](INFO): set local address to 5
2025-05-12T18:06:55.060Z,1747073215.060 [DAT](INFO): Setting time to: 18:6:55 And date to:5/12/2025
2025-05-12T18:06:55.309Z,1747073215.309 [DAT](INFO): DAT read: user:5>

2025-05-12T18:06:55.310Z,1747073215.310 [DAT](INFO): DAT read: Mon May 12, 2025  18:06:55  

2025-05-12T18:06:55.311Z,1747073215.311 [DAT](INFO): Local DAT time set to Mon May 12, 2025  18:06:55  

2025-05-12T18:07:06.158Z,1747073226.158 [NAL9602](INFO): Powering up NAL9602
2025-05-12T18:07:17.019Z,1747073237.019 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:07:34.189Z,1747073254.189 [CommandExec](IMPORTANT): got command failComponent
2025-05-12T18:07:34.189Z,1747073254.189 [CommandExec](IMPORTANT): Failed components:
2025-05-12T18:07:34.190Z,1747073254.190 [CommandExec](IMPORTANT): No failed Components.
2025-05-12T18:07:36.479Z,1747073256.479 [CommandExec](IMPORTANT): got command gfscan
2025-05-12T18:07:36.846Z,1747073256.846 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-12T18:07:41.327Z,1747073261.327 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007237
CHAN A1 (24V): 0.003511
CHAN A2 (12V): -0.005525
CHAN A3 (5V): -0.004167
CHAN B0 (3.3V): -0.001341
CHAN B1 (3.15aV): -0.001871
CHAN B2 (3.15bV): -0.001685
CHAN B3 (GND): -0.001145
OPEN: 0.006644
Full Scale: +/- 1 mA
2025-05-12T18:07:46.881Z,1747073266.881 [CommandExec](IMPORTANT): got command failComponent
2025-05-12T18:07:46.882Z,1747073266.882 [CommandExec](IMPORTANT): Failed components:
2025-05-12T18:07:46.882Z,1747073266.882 [CommandExec](IMPORTANT): No failed Components.
2025-05-12T18:07:50.207Z,1747073270.207 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-05-12T18:08:24.115Z,1747073304.115 [SBIT](IMPORTANT): Beginning Startup BIT
2025-05-12T18:08:24.120Z,1747073304.120 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-12T18:08:28.721Z,1747073308.721 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005924
CHAN A1 (24V): 0.009174
CHAN A2 (12V): -0.005519
CHAN A3 (5V): -0.004441
CHAN B0 (3.3V): -0.001499
CHAN B1 (3.15aV): -0.001794
CHAN B2 (3.15bV): -0.001194
CHAN B3 (GND): -0.000919
OPEN: 0.006921
Full Scale: +/- 1 mA
2025-05-12T18:08:34.588Z,1747073314.588 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-12T18:08:45.831Z,1747073325.831 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005343
2025-05-12T18:09:01.036Z,1747073341.036 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-05-12T18:09:17.929Z,1747073357.929 [SBIT](IMPORTANT): SBIT PASSED
2025-05-12T18:09:17.929Z,1747073357.929 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-05-12T18:09:18.334Z,1747073358.334 [MissionManager](IMPORTANT): Started mission Startup
2025-05-12T18:09:18.335Z,1747073358.335 [Startup] Running Loop=1
2025-05-12T18:09:18.335Z,1747073358.335 [Startup](DEBUG): Aggregate::initialize Startup
2025-05-12T18:09:18.335Z,1747073358.335 [Startup:A.GoToSurface] Running Loop=1
2025-05-12T18:09:18.335Z,1747073358.335 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-12T18:09:18.336Z,1747073358.336 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-12T18:09:18.336Z,1747073358.336 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-12T18:09:18.336Z,1747073358.336 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-12T18:09:18.337Z,1747073358.337 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-12T18:09:18.337Z,1747073358.337 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-12T18:09:18.338Z,1747073358.338 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-12T18:09:18.339Z,1747073358.339 [Startup:StartupSatComms] Running Loop=1
2025-05-12T18:09:18.339Z,1747073358.339 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-05-12T18:09:18.339Z,1747073358.339 [Startup:StartupSatComms:A] Running Loop=1
2025-05-12T18:09:18.748Z,1747073358.748 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-05-12T18:09:27.275Z,1747073367.275 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-05-12T18:09:36.941Z,1747073376.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-05-12T18:09:36.941Z,1747073376.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:09:36.953Z,1747073376.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:09:37.407Z,1747073377.407 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:09:37.407Z,1747073377.407 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-05-12T18:09:37.966Z,1747073377.966 [CommandExec](IMPORTANT): got command burn on
2025-05-12T18:09:37.966Z,1747073377.966 [CommandExec](IMPORTANT): Activating dropweight wire
2025-05-12T18:09:45.806Z,1747073385.806 [CommandExec](IMPORTANT): got command burn off
2025-05-12T18:09:45.806Z,1747073385.806 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-05-12T18:10:18.442Z,1747073418.442 [CommandExec](IMPORTANT): got command configSet list
2025-05-12T18:10:18.443Z,1747073418.443 [CommandExec](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-05-12T18:10:18.640Z,1747073418.640 [Startup:StartupSatComms:A](INFO): Timed out from 2025-05-12T18:09:18.3Z
2025-05-12T18:10:18.640Z,1747073418.640 [Startup:StartupSatComms:A] Stopped
2025-05-12T18:10:18.640Z,1747073418.640 [Startup:StartupSatComms:B] Running Loop=1
2025-05-12T18:10:19.003Z,1747073419.003 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-12T18:10:19.422Z,1747073419.422 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250512T180622/Courier0000.lzma
2025-05-12T18:10:20.282Z,1747073420.282 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-05-12T18:10:20.383Z,1747073420.383 [DataOverHttps](INFO): Moved sent file to Logs/20250512T180622/Courier0000.lzma.bak
2025-05-12T18:10:20.383Z,1747073420.383 [DataOverHttps](INFO): SBD MOMSN=24854681
2025-05-12T18:10:20.686Z,1747073420.686 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-05-12T18:10:36.430Z,1747073436.430 [CommandExec](IMPORTANT): got command restart application
2025-05-12T18:10:37.433Z,1747073437.433 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-05-12T18:10:37.433Z,1747073437.433 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:37.433Z,1747073437.433 [CommandExec](INFO): Uninitializing the command executive.
2025-05-12T18:10:37.433Z,1747073437.433 [CommandExec](INFO): Uninitializing the command scheduler.
2025-05-12T18:10:37.434Z,1747073437.434 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:37.465Z,1747073437.465 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-05-12T18:10:37.465Z,1747073437.465 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-05-12T18:10:37.465Z,1747073437.465 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:37.466Z,1747073437.466 [NavChartDb](INFO): Join timeout helper Thread ID is 991
2025-05-12T18:10:37.745Z,1747073437.745 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:37.745Z,1747073437.745 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:37.765Z,1747073437.765 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-05-12T18:10:37.765Z,1747073437.765 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:37.765Z,1747073437.765 [Radio_Surface](INFO): Join timeout helper Thread ID is 993
2025-05-12T18:10:38.049Z,1747073438.049 [Radio_Surface](INFO): Powering down
2025-05-12T18:10:38.050Z,1747073438.050 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:38.050Z,1747073438.050 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:38.061Z,1747073438.061 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-05-12T18:10:38.061Z,1747073438.061 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:38.061Z,1747073438.061 [Onboard](INFO): Join timeout helper Thread ID is 994
2025-05-12T18:10:41.885Z,1747073441.885 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:41.885Z,1747073441.885 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:41.905Z,1747073441.905 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-05-12T18:10:41.905Z,1747073441.905 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:41.905Z,1747073441.905 [DataOverHttps](INFO): Join timeout helper Thread ID is 997
2025-05-12T18:10:42.549Z,1747073442.549 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:42.554Z,1747073442.554 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:42.578Z,1747073442.578 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-05-12T18:10:42.578Z,1747073442.578 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:42.579Z,1747073442.579 [DAT](INFO): Join timeout helper Thread ID is 998
2025-05-12T18:10:42.737Z,1747073442.737 [DAT](INFO): Powering down
2025-05-12T18:10:42.809Z,1747073442.809 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:42.810Z,1747073442.810 [DAT](INFO): Powering down
2025-05-12T18:10:42.810Z,1747073442.810 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:42.812Z,1747073442.812 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-05-12T18:10:42.812Z,1747073442.812 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:42.813Z,1747073442.813 [BackseatComponent](INFO): Join timeout helper Thread ID is 999
2025-05-12T18:10:42.905Z,1747073442.905 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:42.905Z,1747073442.905 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:42.914Z,1747073442.914 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-05-12T18:10:42.914Z,1747073442.914 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:42.914Z,1747073442.914 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1000
2025-05-12T18:10:43.225Z,1747073443.225 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:43.225Z,1747073443.225 [WetLabsBB2FL](INFO): Powering down
2025-05-12T18:10:43.226Z,1747073443.226 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.233Z,1747073443.233 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-05-12T18:10:43.234Z,1747073443.234 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.234Z,1747073443.234 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1001
2025-05-12T18:10:43.681Z,1747073443.681 [CTD_Seabird](INFO): Powering down
2025-05-12T18:10:43.693Z,1747073443.693 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:43.693Z,1747073443.693 [CTD_Seabird](INFO): Powering down
2025-05-12T18:10:43.705Z,1747073443.705 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.718Z,1747073443.718 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-05-12T18:10:43.719Z,1747073443.719 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.719Z,1747073443.719 [logger](INFO): Join timeout helper Thread ID is 1002
2025-05-12T18:10:43.752Z,1747073443.752 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:43.753Z,1747073443.753 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.757Z,1747073443.757 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-05-12T18:10:43.758Z,1747073443.758 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.758Z,1747073443.758 [CommandLine](INFO): Join timeout helper Thread ID is 1003
2025-05-12T18:10:43.813Z,1747073443.813 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:43.813Z,1747073443.813 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.817Z,1747073443.817 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-05-12T18:10:43.817Z,1747073443.817 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.818Z,1747073443.818 [CommandExec](INFO): Join timeout helper Thread ID is 1004
2025-05-12T18:10:43.819Z,1747073443.819 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-05-12T18:10:43.819Z,1747073443.819 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:43.820Z,1747073443.820 [controlThread](INFO): Join timeout helper Thread ID is 1005
2025-05-12T18:10:44.005Z,1747073444.005 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:10:44.005Z,1747073444.005 [controlThread](DEBUG): Uninitializing ControlThread
2025-05-12T18:10:44.006Z,1747073444.006 [AHRS_M2](INFO): Powering down
2025-05-12T18:10:44.181Z,1747073444.181 [DVL_micro](INFO): Powering down
2025-05-12T18:10:44.182Z,1747073444.182 [NAL9602](INFO): Powering down
2025-05-12T18:10:44.183Z,1747073444.183 [Sonardyne_Nano](INFO): Powering down
2025-05-12T18:10:44.389Z,1747073444.389 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-05-12T18:10:44.390Z,1747073444.390 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-05-12T18:10:44.390Z,1747073444.390 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-05-12T18:10:44.391Z,1747073444.391 [MissionManager](INFO): Uninitializing Mission Default
2025-05-12T18:10:44.393Z,1747073444.393 [MissionManager](INFO): Uninitializing Mission Startup
2025-05-12T18:10:44.393Z,1747073444.393 [Startup] Stopped
2025-05-12T18:10:44.394Z,1747073444.394 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-05-12T18:10:44.394Z,1747073444.394 [Startup:A.GoToSurface] Stopped
2025-05-12T18:10:44.394Z,1747073444.394 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-12T18:10:44.394Z,1747073444.394 [Startup:StartupSatComms] Stopped
2025-05-12T18:10:44.394Z,1747073444.394 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-05-12T18:10:44.394Z,1747073444.394 [Startup:StartupSatComms:B] Stopped
2025-05-12T18:10:44.396Z,1747073444.396 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-05-12T18:10:44.396Z,1747073444.396 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-05-12T18:10:44.396Z,1747073444.396 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-05-12T18:10:44.397Z,1747073444.397 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-05-12T18:10:44.397Z,1747073444.397 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-05-12T18:10:44.397Z,1747073444.397 [BuoyancyServo](INFO): Powering down
2025-05-12T18:10:44.409Z,1747073444.409 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-05-12T18:10:44.409Z,1747073444.409 [ElevatorServo](INFO): Powering down
2025-05-12T18:10:44.410Z,1747073444.410 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-05-12T18:10:44.410Z,1747073444.410 [MassServo](INFO): Powering down
2025-05-12T18:10:44.411Z,1747073444.411 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-12T18:10:44.411Z,1747073444.411 [RudderServo](INFO): Powering down
2025-05-12T18:10:44.411Z,1747073444.411 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-05-12T18:10:44.412Z,1747073444.412 [ThrusterHE](INFO): Powering down
2025-05-12T18:10:44.413Z,1747073444.413 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-05-12T18:10:44.413Z,1747073444.413 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-05-12T18:10:44.413Z,1747073444.413 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-05-12T18:10:44.413Z,1747073444.413 [CBIT](DEBUG): Powering off loads.
2025-05-12T18:10:44.424Z,1747073444.424 [CBIT](DEBUG): Disabling WDT.
2025-05-12T18:10:44.437Z,1747073444.437 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-12T18:10:44.437Z,1747073444.437 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-12T18:10:44.438Z,1747073444.438 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.457Z,1747073444.457 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-12T18:10:44.457Z,1747073444.457 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-12T18:10:44.472Z,1747073444.472 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.479Z,1747073444.479 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.510Z,1747073444.510 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.514Z,1747073444.514 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.522Z,1747073444.522 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.533Z,1747073444.533 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.578Z,1747073444.578 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.632Z,1747073444.632 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:10:44.690Z,1747073444.690 [logger ThreadHandler](INFO): Thread cancelled.