2025-05-09T20:05:42.528Z,1746821142.528 [Supervisor](DEBUG): Initializing supervisor.
2025-05-09T20:05:42.532Z,1746821142.532 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-09T20:05:42.533Z,1746821142.533 [SyncHandler](INFO): Protected caller Thread ID is 8560
2025-05-09T20:05:42.533Z,1746821142.533 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-09T20:05:42.534Z,1746821142.534 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-09T20:05:42.534Z,1746821142.534 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8561
2025-05-09T20:05:42.538Z,1746821142.538 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-09T20:05:42.555Z,1746821142.555 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-09T20:05:42.556Z,1746821142.556 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-09T20:05:42.556Z,1746821142.556 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8562
2025-05-09T20:05:42.561Z,1746821142.561 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-09T20:05:42.561Z,1746821142.561 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-09T20:05:42.562Z,1746821142.562 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8563
2025-05-09T20:05:42.564Z,1746821142.564 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-09T20:05:42.565Z,1746821142.565 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-09T20:05:42.565Z,1746821142.565 [logger ThreadHandler](INFO): Protected caller Thread ID is 8564
2025-05-09T20:05:42.569Z,1746821142.569 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-09T20:05:42.569Z,1746821142.569 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-09T20:05:42.571Z,1746821142.571 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-09T20:05:42.926Z,1746821142.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-09T20:05:42.927Z,1746821142.927 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-09T20:05:43.788Z,1746821143.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-09T20:05:43.789Z,1746821143.789 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-09T20:05:43.911Z,1746821143.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-09T20:05:43.912Z,1746821143.912 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-09T20:05:44.485Z,1746821144.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-09T20:05:44.486Z,1746821144.486 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-09T20:05:44.595Z,1746821144.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-09T20:05:44.595Z,1746821144.595 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-09T20:05:44.688Z,1746821144.688 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-09T20:05:45.125Z,1746821145.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-09T20:05:45.126Z,1746821145.126 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-09T20:05:45.373Z,1746821145.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-09T20:05:45.373Z,1746821145.373 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-09T20:05:45.525Z,1746821145.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-09T20:05:45.525Z,1746821145.525 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-09T20:05:45.791Z,1746821145.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-09T20:05:45.792Z,1746821145.792 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-09T20:05:46.364Z,1746821146.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-09T20:05:46.364Z,1746821146.364 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-09T20:05:46.769Z,1746821146.769 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-09T20:05:46.770Z,1746821146.770 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-09T20:05:46.863Z,1746821146.863 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-09T20:05:47.075Z,1746821147.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-09T20:05:47.076Z,1746821147.076 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-09T20:05:47.338Z,1746821147.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-09T20:05:47.339Z,1746821147.339 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-09T20:05:48.063Z,1746821148.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-09T20:05:48.065Z,1746821148.065 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2025-05-09T20:05:48.065Z,1746821148.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2025-05-09T20:05:48.189Z,1746821148.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2025-05-09T20:05:48.451Z,1746821148.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2025-05-09T20:05:48.549Z,1746821148.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2025-05-09T20:05:48.645Z,1746821148.645 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2025-05-09T20:05:48.767Z,1746821148.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2025-05-09T20:05:48.864Z,1746821148.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2025-05-09T20:05:48.982Z,1746821148.982 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2025-05-09T20:05:49.255Z,1746821149.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-09T20:05:49.256Z,1746821149.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2025-05-09T20:05:49.404Z,1746821149.404 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2025-05-09T20:05:49.510Z,1746821149.510 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2025-05-09T20:05:49.619Z,1746821149.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2025-05-09T20:05:49.728Z,1746821149.728 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2025-05-09T20:05:49.729Z,1746821149.729 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-05-09T20:05:49.748Z,1746821149.748 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-09T20:05:49.856Z,1746821149.856 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-09T20:05:49.857Z,1746821149.857 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-09T20:05:49.867Z,1746821149.867 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-09T20:05:49.868Z,1746821149.868 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-09T20:05:49.917Z,1746821149.917 [DepthRateCalculator] Loaded
2025-05-09T20:05:49.917Z,1746821149.917 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-09T20:05:49.923Z,1746821149.923 [PitchRateCalculator] Loaded
2025-05-09T20:05:49.923Z,1746821149.923 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-09T20:05:49.933Z,1746821149.933 [SpeedCalculator] Loaded
2025-05-09T20:05:49.933Z,1746821149.933 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-09T20:05:49.938Z,1746821149.938 [YawRateCalculator] Loaded
2025-05-09T20:05:49.938Z,1746821149.938 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-09T20:05:49.958Z,1746821149.958 [ElevatorOffsetCalculator] Loaded
2025-05-09T20:05:49.959Z,1746821149.959 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-09T20:05:49.959Z,1746821149.959 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-09T20:05:49.960Z,1746821149.960 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-09T20:05:50.010Z,1746821150.010 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-09T20:05:50.063Z,1746821150.063 [VerticalControl] Loaded
2025-05-09T20:05:50.063Z,1746821150.063 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-09T20:05:50.066Z,1746821150.066 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-09T20:05:50.111Z,1746821150.111 [HorizontalControl] Loaded
2025-05-09T20:05:50.111Z,1746821150.111 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-09T20:05:50.114Z,1746821150.114 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-09T20:05:50.116Z,1746821150.116 [SpeedControl] Loaded
2025-05-09T20:05:50.117Z,1746821150.117 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-09T20:05:50.119Z,1746821150.119 [LoopControl](DEBUG): Construct LoopControl.
2025-05-09T20:05:50.120Z,1746821150.120 [LoopControl] Loaded
2025-05-09T20:05:50.120Z,1746821150.120 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-09T20:05:50.120Z,1746821150.120 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-09T20:05:50.121Z,1746821150.121 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-09T20:05:50.231Z,1746821150.231 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-09T20:05:50.232Z,1746821150.232 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-09T20:05:50.245Z,1746821150.245 [NavChart] Loaded
2025-05-09T20:05:50.245Z,1746821150.245 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-09T20:05:50.251Z,1746821150.251 [UniversalFixResidualReporter] Loaded
2025-05-09T20:05:50.251Z,1746821150.251 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-09T20:05:50.251Z,1746821150.251 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-09T20:05:50.252Z,1746821150.252 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-09T20:05:50.419Z,1746821150.419 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-09T20:05:50.420Z,1746821150.420 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-09T20:05:50.874Z,1746821150.874 [AHRS_M2] Loaded
2025-05-09T20:05:50.874Z,1746821150.874 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-09T20:05:50.912Z,1746821150.912 [BackseatComponent] Loaded
2025-05-09T20:05:50.913Z,1746821150.913 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-09T20:05:50.914Z,1746821150.914 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409384E0
2025-05-09T20:05:50.914Z,1746821150.914 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8650
2025-05-09T20:05:50.917Z,1746821150.917 [LcmUniversalReporter] Loaded
2025-05-09T20:05:50.917Z,1746821150.917 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-09T20:05:51.723Z,1746821151.723 [BPC1] Loaded
2025-05-09T20:05:51.724Z,1746821151.724 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-09T20:05:51.811Z,1746821151.811 [DATMMP] Loaded
2025-05-09T20:05:51.811Z,1746821151.811 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2025-05-09T20:05:51.812Z,1746821151.812 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409694E0
2025-05-09T20:05:51.812Z,1746821151.812 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 8651
2025-05-09T20:05:51.884Z,1746821151.884 [DataOverHttps] Loaded
2025-05-09T20:05:51.884Z,1746821151.884 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-09T20:05:51.885Z,1746821151.885 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2025-05-09T20:05:51.885Z,1746821151.885 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8652
2025-05-09T20:05:51.907Z,1746821151.907 [Depth_Keller] Loaded
2025-05-09T20:05:51.908Z,1746821151.908 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-05-09T20:05:51.946Z,1746821151.946 [DVL_micro] Loaded
2025-05-09T20:05:51.947Z,1746821151.947 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-05-09T20:05:52.011Z,1746821152.011 [NAL9602] Loaded
2025-05-09T20:05:52.011Z,1746821152.011 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-09T20:05:52.040Z,1746821152.040 [Onboard] Loaded
2025-05-09T20:05:52.040Z,1746821152.040 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-09T20:05:52.041Z,1746821152.041 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0
2025-05-09T20:05:52.042Z,1746821152.042 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8653
2025-05-09T20:05:52.055Z,1746821152.055 [Power24vConverter] Loaded
2025-05-09T20:05:52.056Z,1746821152.056 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-09T20:05:52.071Z,1746821152.071 [Radio_Surface] Loaded
2025-05-09T20:05:52.071Z,1746821152.071 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-09T20:05:52.072Z,1746821152.072 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0
2025-05-09T20:05:52.072Z,1746821152.072 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8654
2025-05-09T20:05:52.087Z,1746821152.087 [Sonardyne_Nano] Loaded
2025-05-09T20:05:52.088Z,1746821152.088 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-05-09T20:05:52.088Z,1746821152.088 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-09T20:05:52.089Z,1746821152.089 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-09T20:05:52.218Z,1746821152.218 [BuoyancyServo] Loaded
2025-05-09T20:05:52.218Z,1746821152.218 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-09T20:05:52.240Z,1746821152.240 [ElevatorServo] Loaded
2025-05-09T20:05:52.240Z,1746821152.240 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-09T20:05:52.262Z,1746821152.262 [MassServo] Loaded
2025-05-09T20:05:52.262Z,1746821152.262 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-09T20:05:52.282Z,1746821152.282 [RudderServo] Loaded
2025-05-09T20:05:52.282Z,1746821152.282 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-09T20:05:52.298Z,1746821152.298 [ThrusterHE] Loaded
2025-05-09T20:05:52.298Z,1746821152.298 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-05-09T20:05:52.298Z,1746821152.298 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-09T20:05:52.299Z,1746821152.299 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-09T20:05:52.367Z,1746821152.367 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-09T20:05:52.369Z,1746821152.369 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-09T20:05:52.477Z,1746821152.477 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-09T20:05:52.478Z,1746821152.478 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-09T20:05:53.087Z,1746821153.087 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-09T20:05:53.087Z,1746821153.087 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-09T20:05:53.262Z,1746821153.262 [CTD_Seabird] Loaded
2025-05-09T20:05:53.263Z,1746821153.263 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-09T20:05:53.264Z,1746821153.264 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB34E0
2025-05-09T20:05:53.264Z,1746821153.264 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8655
2025-05-09T20:05:53.299Z,1746821153.299 [ESPComponent] Loaded
2025-05-09T20:05:53.299Z,1746821153.299 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-05-09T20:05:53.320Z,1746821153.320 [PAR_Licor] Loaded
2025-05-09T20:05:53.321Z,1746821153.321 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-05-09T20:05:53.364Z,1746821153.364 [WetLabsBB2FL] Loaded
2025-05-09T20:05:53.364Z,1746821153.364 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-05-09T20:05:53.365Z,1746821153.365 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE34E0
2025-05-09T20:05:53.366Z,1746821153.366 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8656
2025-05-09T20:05:53.367Z,1746821153.367 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-09T20:05:53.367Z,1746821153.367 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-09T20:05:53.502Z,1746821153.502 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-09T20:05:53.511Z,1746821153.511 [SBIT] Loaded
2025-05-09T20:05:53.512Z,1746821153.512 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-09T20:05:53.514Z,1746821153.514 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-09T20:05:53.527Z,1746821153.527 [IBIT] Loaded
2025-05-09T20:05:53.527Z,1746821153.527 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-09T20:05:53.532Z,1746821153.532 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-09T20:05:53.635Z,1746821153.635 [CBIT] Loaded
2025-05-09T20:05:53.636Z,1746821153.636 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-09T20:05:53.636Z,1746821153.636 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-09T20:05:53.642Z,1746821153.642 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-09T20:05:53.645Z,1746821153.645 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-09T20:05:53.656Z,1746821153.656 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-09T20:05:53.657Z,1746821153.657 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2025-05-09T20:05:53.658Z,1746821153.658 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8657
2025-05-09T20:05:53.662Z,1746821153.662 [Supervisor](INFO): Main Thread ID is 8559
2025-05-09T20:05:53.662Z,1746821153.662 [Supervisor](DEBUG): Running supervisor.
2025-05-09T20:05:53.663Z,1746821153.663 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8658
2025-05-09T20:05:53.663Z,1746821153.663 [CommandExec](INFO): Initializing the command executive.
2025-05-09T20:05:53.665Z,1746821153.665 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8659
2025-05-09T20:05:53.667Z,1746821153.667 [controlThread ThreadHandler](INFO): Handler Thread ID is 8660
2025-05-09T20:05:53.667Z,1746821153.667 [controlThread](DEBUG): Initializing ControlThread
2025-05-09T20:05:53.668Z,1746821153.668 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-09T20:05:53.669Z,1746821153.669 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-09T20:05:53.669Z,1746821153.669 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-09T20:05:53.669Z,1746821153.669 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-09T20:05:53.670Z,1746821153.670 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-09T20:05:53.670Z,1746821153.670 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-09T20:05:53.672Z,1746821153.672 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-09T20:05:53.673Z,1746821153.673 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-09T20:05:53.673Z,1746821153.673 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-09T20:05:53.674Z,1746821153.674 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-09T20:05:53.674Z,1746821153.674 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-09T20:05:53.683Z,1746821153.683 [SBIT](INFO): Initialize SBIT Component.
2025-05-09T20:05:53.683Z,1746821153.683 [SBIT](IMPORTANT): git: 2024-09-17
2025-05-09T20:05:53.683Z,1746821153.683 [SBIT](INFO): git hash: 40e9ce87bff78b617219447905233bfcf6062b37
2025-05-09T20:05:53.684Z,1746821153.684 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-09T20:05:53.685Z,1746821153.685 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-05-09T20:05:53.686Z,1746821153.686 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2025-05-09T20:05:53.686Z,1746821153.686 [IBIT](INFO): Initialize IBIT Component.
2025-05-09T20:05:53.687Z,1746821153.687 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-09T20:05:53.688Z,1746821153.688 [logger ThreadHandler](INFO): Handler Thread ID is 8661
2025-05-09T20:05:53.698Z,1746821153.698 [CBIT](DEBUG): Initialized mux pins.
2025-05-09T20:05:53.698Z,1746821153.698 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-09T20:05:53.707Z,1746821153.707 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8662
2025-05-09T20:05:53.719Z,1746821153.719 [DATMMP ThreadHandler](INFO): Handler Thread ID is 8663
2025-05-09T20:05:53.719Z,1746821153.719 [DATMMP](INFO): Start
2025-05-09T20:05:53.720Z,1746821153.720 [DATMMP](INFO): Powering up
2025-05-09T20:05:53.720Z,1746821153.720 [DATMMP](DEBUG): Initializing DATMMP.
2025-05-09T20:05:53.722Z,1746821153.722 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-05-09T20:05:53.723Z,1746821153.723 [CBIT](DEBUG): Initializing heartbeat.
2025-05-09T20:05:53.725Z,1746821153.725 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8665
2025-05-09T20:05:53.726Z,1746821153.726 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-09T20:05:53.735Z,1746821153.735 [Onboard ThreadHandler](INFO): Handler Thread ID is 8666
2025-05-09T20:05:53.752Z,1746821153.752 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8667
2025-05-09T20:05:53.775Z,1746821153.775 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8668
2025-05-09T20:05:53.775Z,1746821153.775 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-09T20:05:53.780Z,1746821153.780 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8670
2025-05-09T20:05:53.783Z,1746821153.783 [WetLabsBB2FL](INFO): Powering up
2025-05-09T20:05:53.784Z,1746821153.784 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8672
2025-05-09T20:05:53.787Z,1746821153.787 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-09T20:05:53.787Z,1746821153.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-09T20:05:53.787Z,1746821153.787 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-09T20:05:53.787Z,1746821153.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-09T20:05:53.787Z,1746821153.787 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-09T20:05:53.788Z,1746821153.788 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-09T20:05:53.788Z,1746821153.788 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-09T20:05:53.788Z,1746821153.788 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-09T20:05:53.794Z,1746821153.794 [CBIT](DEBUG): Deactivating GF circuits.
2025-05-09T20:05:53.795Z,1746821153.795 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-09T20:05:53.834Z,1746821153.834 [CBIT](DEBUG): Backplane powered.
2025-05-09T20:05:53.835Z,1746821153.835 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-09T20:05:53.836Z,1746821153.836 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-05-09T20:05:53.836Z,1746821153.836 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-05-09T20:05:53.837Z,1746821153.837 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-05-09T20:05:53.880Z,1746821153.880 [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-09T20:05:53.884Z,1746821153.884 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-09T20:05:53.928Z,1746821153.928 [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-09T20:05:53.929Z,1746821153.929 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-05-09T20:05:53.929Z,1746821153.929 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-05-09T20:05:53.930Z,1746821153.930 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-05-09T20:05:53.970Z,1746821153.970 [DATMMP](INFO): Starting
2025-05-09T20:05:54.158Z,1746821154.158 [Radio_Surface](INFO): Powering up
2025-05-09T20:05:54.206Z,1746821154.206 [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-09T20:05:54.222Z,1746821154.222 [DATMMP](INFO): Starting
2025-05-09T20:05:54.228Z,1746821154.228 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-09T20:05:54.238Z,1746821154.238 [Default:A.Wait](DEBUG): Construct Wait.
2025-05-09T20:05:54.241Z,1746821154.241 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-09T20:05:54.265Z,1746821154.265 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-05-09T20:05:54.284Z,1746821154.284 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-09T20:05:54.298Z,1746821154.298 [Default:E.Execute](DEBUG): Construct Execute.
2025-05-09T20:05:54.301Z,1746821154.301 [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-09T20:05:54.310Z,1746821154.310 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-09T20:05:54.326Z,1746821154.326 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-09T20:05:54.404Z,1746821154.404 [Power24vConverter](INFO): Powering up.
2025-05-09T20:05:54.404Z,1746821154.404 [Sonardyne_Nano](INFO): Initializing.
2025-05-09T20:05:54.464Z,1746821154.464 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-09T20:05:54.471Z,1746821154.471 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-09T20:05:54.472Z,1746821154.472 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-09T20:05:54.475Z,1746821154.475 [DATMMP](INFO): Starting
2025-05-09T20:05:54.479Z,1746821154.479 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-09T20:05:54.480Z,1746821154.480 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-09T20:05:54.487Z,1746821154.487 [MassServo](DEBUG): Initializing MassServo.
2025-05-09T20:05:54.488Z,1746821154.488 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-09T20:05:54.495Z,1746821154.495 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-09T20:05:54.496Z,1746821154.496 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-05-09T20:05:54.503Z,1746821154.503 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-05-09T20:05:54.732Z,1746821154.732 [DATMMP](INFO): Starting
2025-05-09T20:05:54.986Z,1746821154.986 [DATMMP](INFO): Starting
2025-05-09T20:05:55.238Z,1746821155.238 [DATMMP](INFO): Starting
2025-05-09T20:05:55.490Z,1746821155.490 [DATMMP](INFO): Starting
2025-05-09T20:05:55.742Z,1746821155.742 [DATMMP](INFO): Starting
2025-05-09T20:05:55.994Z,1746821155.994 [DATMMP](INFO): Starting
2025-05-09T20:05:56.246Z,1746821156.246 [DATMMP](INFO): Starting
2025-05-09T20:05:56.498Z,1746821156.498 [DATMMP](INFO): Starting
2025-05-09T20:05:56.751Z,1746821156.751 [DATMMP](INFO): Starting
2025-05-09T20:05:57.002Z,1746821157.002 [DATMMP](INFO): Starting
2025-05-09T20:05:57.255Z,1746821157.255 [DATMMP](INFO): Starting
2025-05-09T20:05:57.506Z,1746821157.506 [DATMMP](INFO): Starting
2025-05-09T20:05:57.758Z,1746821157.758 [DATMMP](INFO): Starting
2025-05-09T20:05:57.866Z,1746821157.866 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-05-09T20:05:58.012Z,1746821158.012 [DATMMP](INFO): Starting
2025-05-09T20:05:58.262Z,1746821158.262 [DATMMP](INFO): Starting
2025-05-09T20:05:58.278Z,1746821158.278 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-05-09T20:05:58.514Z,1746821158.514 [DATMMP](INFO): Starting
2025-05-09T20:05:58.578Z,1746821158.578 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-05-09T20:05:58.766Z,1746821158.766 [DATMMP](INFO): Starting
2025-05-09T20:05:59.019Z,1746821159.019 [DATMMP](INFO): Starting
2025-05-09T20:05:59.070Z,1746821159.070 [WetLabsBB2FL] Communications Fault, FailCount= 1
2025-05-09T20:05:59.070Z,1746821159.070 [WetLabsBB2FL](ERROR): Communications Fault
2025-05-09T20:05:59.272Z,1746821159.272 [DATMMP](INFO): Starting
2025-05-09T20:05:59.523Z,1746821159.523 [DATMMP](INFO): Starting
2025-05-09T20:05:59.524Z,1746821159.524 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-05-09T20:05:59.570Z,1746821159.570 [WetLabsBB2FL](INFO): Powering down
2025-05-09T20:05:59.775Z,1746821159.775 [DATMMP](INFO): Starting
2025-05-09T20:05:59.803Z,1746821159.803 [CommandExec](IMPORTANT): got command quit
2025-05-09T20:06:00.026Z,1746821160.026 [DATMMP](INFO): Starting
2025-05-09T20:06:00.207Z,1746821160.207 [RudderServo](FAULT): Overload Error
2025-05-09T20:06:00.207Z,1746821160.207 [RudderServo] Hardware Fault, FailCount= 1
2025-05-09T20:06:00.207Z,1746821160.207 [RudderServo](ERROR): Hardware Fault
2025-05-09T20:06:00.235Z,1746821160.235 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-05-09T20:06:00.279Z,1746821160.279 [DATMMP](INFO): Starting
2025-05-09T20:06:00.298Z,1746821160.298 [CBIT](ERROR): Hardware Fault in component: RudderServo
2025-05-09T20:06:00.530Z,1746821160.530 [DATMMP](INFO): Starting
2025-05-09T20:06:00.566Z,1746821160.566 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:00.615Z,1746821160.615 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-09T20:06:00.615Z,1746821160.615 [RudderServo](INFO): Powering down
2025-05-09T20:06:00.738Z,1746821160.738 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-05-09T20:06:00.739Z,1746821160.739 [WetLabsBB2FL] No Fault, FailCount= 1
2025-05-09T20:06:00.782Z,1746821160.782 [DATMMP](INFO): Starting
2025-05-09T20:06:00.795Z,1746821160.795 [WetLabsBB2FL](INFO): Powering up
2025-05-09T20:06:00.806Z,1746821160.806 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-05-09T20:06:00.807Z,1746821160.807 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:00.807Z,1746821160.807 [CommandExec](INFO): Uninitializing the command executive.
2025-05-09T20:06:00.807Z,1746821160.807 [CommandExec](INFO): Uninitializing the command scheduler.
2025-05-09T20:06:00.807Z,1746821160.807 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:00.954Z,1746821160.954 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-05-09T20:06:00.954Z,1746821160.954 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-05-09T20:06:00.955Z,1746821160.955 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:00.955Z,1746821160.955 [NavChartDb](INFO): Join timeout helper Thread ID is 8682
2025-05-09T20:06:01.034Z,1746821161.034 [DATMMP](INFO): Starting
2025-05-09T20:06:01.182Z,1746821161.182 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:01.286Z,1746821161.286 [DATMMP](INFO): Starting
2025-05-09T20:06:01.539Z,1746821161.539 [DATMMP](INFO): Starting
2025-05-09T20:06:01.610Z,1746821161.610 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:01.664Z,1746821161.664 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-09T20:06:01.784Z,1746821161.784 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-09T20:06:01.788Z,1746821161.788 [CBIT](INFO): Clearing failed state for component RudderServo
2025-05-09T20:06:01.788Z,1746821161.788 [RudderServo] No Fault, FailCount= 1
2025-05-09T20:06:01.790Z,1746821161.790 [DATMMP](INFO): Starting
2025-05-09T20:06:02.042Z,1746821162.042 [DATMMP](INFO): Starting
2025-05-09T20:06:02.130Z,1746821162.130 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:02.295Z,1746821162.295 [DATMMP](INFO): Starting
2025-05-09T20:06:02.546Z,1746821162.546 [DATMMP](INFO): Starting
2025-05-09T20:06:02.547Z,1746821162.547 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:02.799Z,1746821162.799 [DATMMP](INFO): Starting
2025-05-09T20:06:03.003Z,1746821163.003 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:03.050Z,1746821163.050 [DATMMP](INFO): Starting
2025-05-09T20:06:03.302Z,1746821163.302 [DATMMP](INFO): Starting
2025-05-09T20:06:03.430Z,1746821163.430 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:03.557Z,1746821163.557 [DATMMP](INFO): Starting
2025-05-09T20:06:03.806Z,1746821163.806 [DATMMP](INFO): Starting
2025-05-09T20:06:03.858Z,1746821163.858 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:03.964Z,1746821163.964 [CBIT](CRITICAL): Environmental Failure. Press:14.762117 PSI. Humidity:44%. Temp:24 C. ABORTING MISSION
2025-05-09T20:06:04.059Z,1746821164.059 [DATMMP](INFO): Starting
2025-05-09T20:06:04.311Z,1746821164.311 [DATMMP](INFO): Starting
2025-05-09T20:06:04.314Z,1746821164.314 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:04.412Z,1746821164.412 [CBIT](INFO): Critical error at 20250509T200603
2025-05-09T20:06:04.466Z,1746821164.466 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:04.467Z,1746821164.467 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:04.478Z,1746821164.478 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-05-09T20:06:04.478Z,1746821164.478 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:04.479Z,1746821164.479 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8684
2025-05-09T20:06:04.562Z,1746821164.562 [DATMMP](INFO): Starting
2025-05-09T20:06:04.563Z,1746821164.563 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:04.659Z,1746821164.659 [WetLabsBB2FL](INFO): Powering down
2025-05-09T20:06:04.660Z,1746821164.660 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:04.660Z,1746821164.660 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-05-09T20:06:04.660Z,1746821164.660 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:04.661Z,1746821164.661 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8685
2025-05-09T20:06:04.734Z,1746821164.734 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:04.815Z,1746821164.815 [DATMMP](INFO): Starting
2025-05-09T20:06:04.823Z,1746821164.823 [CTD_Seabird](INFO): Powering down
2025-05-09T20:06:04.954Z,1746821164.954 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:04.955Z,1746821164.955 [CTD_Seabird](INFO): Powering down
2025-05-09T20:06:04.966Z,1746821164.966 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:04.982Z,1746821164.982 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-05-09T20:06:04.982Z,1746821164.982 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:04.983Z,1746821164.983 [Radio_Surface](INFO): Join timeout helper Thread ID is 8686
2025-05-09T20:06:05.066Z,1746821165.066 [DATMMP](INFO): Starting
2025-05-09T20:06:05.067Z,1746821165.067 [Radio_Surface](INFO): Powering down
2025-05-09T20:06:05.068Z,1746821165.068 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:05.068Z,1746821165.068 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:05.082Z,1746821165.082 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-05-09T20:06:05.082Z,1746821165.082 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:05.083Z,1746821165.083 [Onboard](INFO): Join timeout helper Thread ID is 8687
2025-05-09T20:06:05.150Z,1746821165.150 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:05.319Z,1746821165.319 [DATMMP](INFO): Starting
2025-05-09T20:06:05.571Z,1746821165.571 [DATMMP](INFO): Starting
2025-05-09T20:06:05.594Z,1746821165.594 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:05.823Z,1746821165.823 [DATMMP](INFO): Starting
2025-05-09T20:06:05.823Z,1746821165.823 [DATMMP](INFO): DAT read: 

2025-05-09T20:06:05.824Z,1746821165.824 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-05-09T20:06:06.010Z,1746821166.010 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:06.074Z,1746821166.074 [DATMMP](INFO): Starting
2025-05-09T20:06:06.326Z,1746821166.326 [DATMMP](INFO): Starting
2025-05-09T20:06:06.426Z,1746821166.426 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:06.579Z,1746821166.579 [DATMMP](INFO): Starting
2025-05-09T20:06:06.831Z,1746821166.831 [DATMMP](INFO): Starting
2025-05-09T20:06:06.831Z,1746821166.831 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:06.911Z,1746821166.911 [RudderServo](FAULT): Overload Error
2025-05-09T20:06:06.911Z,1746821166.911 [RudderServo] Hardware Fault, FailCount= 2
2025-05-09T20:06:06.911Z,1746821166.911 [RudderServo](ERROR): Hardware Fault
2025-05-09T20:06:06.939Z,1746821166.939 [CBIT](ERROR): Hardware Fault in component: RudderServo
2025-05-09T20:06:07.083Z,1746821167.083 [DATMMP](INFO): Starting
2025-05-09T20:06:07.270Z,1746821167.270 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2025-05-09T20:06:07.270Z,1746821167.270 [AHRS_M2](FAULT): Failed to initialize within timeout.
2025-05-09T20:06:07.270Z,1746821167.270 [AHRS_M2] Communications Fault, FailCount= 1
2025-05-09T20:06:07.271Z,1746821167.271 [AHRS_M2](ERROR): Communications Fault
2025-05-09T20:06:07.332Z,1746821167.332 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-09T20:06:07.332Z,1746821167.332 [RudderServo](INFO): Powering down
2025-05-09T20:06:07.335Z,1746821167.335 [DATMMP](INFO): Starting
2025-05-09T20:06:07.337Z,1746821167.337 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2025-05-09T20:06:07.347Z,1746821167.347 [AHRS_M2](INFO): Powering down
2025-05-09T20:06:07.587Z,1746821167.587 [DATMMP](INFO): Starting
2025-05-09T20:06:07.587Z,1746821167.587 [DATMMP](INFO): DAT read: MF Frequency Band

2025-05-09T20:06:07.588Z,1746821167.588 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-05-09T20:06:07.589Z,1746821167.589 [DATMMP](INFO): DAT read: Apr  7 2000 18:57:33 

2025-05-09T20:06:07.824Z,1746821167.824 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-09T20:06:07.839Z,1746821167.839 [DATMMP](INFO): Starting
2025-05-09T20:06:07.943Z,1746821167.943 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-09T20:06:07.947Z,1746821167.947 [CBIT](INFO): Clearing failed state for component RudderServo
2025-05-09T20:06:07.948Z,1746821167.948 [RudderServo] No Fault, FailCount= 2
2025-05-09T20:06:08.090Z,1746821168.090 [DATMMP](INFO): Starting
2025-05-09T20:06:08.227Z,1746821168.227 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-05-09T20:06:08.227Z,1746821168.227 [AHRS_M2] No Fault, FailCount= 1
2025-05-09T20:06:08.342Z,1746821168.342 [DATMMP](INFO): Starting
2025-05-09T20:06:08.559Z,1746821168.559 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-09T20:06:08.595Z,1746821168.595 [DATMMP](INFO): Starting
2025-05-09T20:06:08.597Z,1746821168.597 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2025-05-09T20:06:08.747Z,1746821168.747 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:08.747Z,1746821168.747 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:08.763Z,1746821168.763 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-05-09T20:06:08.763Z,1746821168.763 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:08.764Z,1746821168.764 [DataOverHttps](INFO): Join timeout helper Thread ID is 8690
2025-05-09T20:06:08.847Z,1746821168.847 [DATMMP](INFO): Starting
2025-05-09T20:06:08.848Z,1746821168.848 [DATMMP](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP

2025-05-09T20:06:08.848Z,1746821168.848 [DATMMP](INFO): commRate: 600
2025-05-09T20:06:09.099Z,1746821169.099 [DATMMP](INFO): Starting
2025-05-09T20:06:09.183Z,1746821169.183 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:09.187Z,1746821169.187 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.191Z,1746821169.191 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2025-05-09T20:06:09.191Z,1746821169.191 [DATMMP ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.192Z,1746821169.192 [DATMMP](INFO): Join timeout helper Thread ID is 8691
2025-05-09T20:06:09.351Z,1746821169.351 [DATMMP](INFO): Stop
2025-05-09T20:06:09.351Z,1746821169.351 [DATMMP](INFO): uninitialize
2025-05-09T20:06:09.351Z,1746821169.351 [DATMMP](INFO): Powering down
2025-05-09T20:06:09.423Z,1746821169.423 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:09.423Z,1746821169.423 [DATMMP](INFO): uninitialize
2025-05-09T20:06:09.423Z,1746821169.423 [DATMMP](INFO): Powering down
2025-05-09T20:06:09.424Z,1746821169.424 [DATMMP ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.439Z,1746821169.439 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-05-09T20:06:09.439Z,1746821169.439 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.440Z,1746821169.440 [BackseatComponent](INFO): Join timeout helper Thread ID is 8692
2025-05-09T20:06:09.623Z,1746821169.623 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:09.623Z,1746821169.623 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.635Z,1746821169.635 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-05-09T20:06:09.635Z,1746821169.635 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.636Z,1746821169.636 [logger](INFO): Join timeout helper Thread ID is 8693
2025-05-09T20:06:09.662Z,1746821169.662 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:09.662Z,1746821169.662 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.675Z,1746821169.675 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-05-09T20:06:09.675Z,1746821169.675 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.676Z,1746821169.676 [CommandLine](INFO): Join timeout helper Thread ID is 8694
2025-05-09T20:06:09.731Z,1746821169.731 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:09.731Z,1746821169.731 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.735Z,1746821169.735 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-05-09T20:06:09.735Z,1746821169.735 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.736Z,1746821169.736 [CommandExec](INFO): Join timeout helper Thread ID is 8695
2025-05-09T20:06:09.737Z,1746821169.737 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-05-09T20:06:09.737Z,1746821169.737 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:09.737Z,1746821169.737 [controlThread](INFO): Join timeout helper Thread ID is 8696
2025-05-09T20:06:09.766Z,1746821169.766 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-09T20:06:09.767Z,1746821169.767 [controlThread](DEBUG): Uninitializing ControlThread
2025-05-09T20:06:09.767Z,1746821169.767 [AHRS_M2](INFO): Powering down
2025-05-09T20:06:09.943Z,1746821169.943 [DVL_micro](INFO): Powering down
2025-05-09T20:06:09.944Z,1746821169.944 [NAL9602](INFO): Powering down
2025-05-09T20:06:09.945Z,1746821169.945 [Sonardyne_Nano](INFO): Powering down
2025-05-09T20:06:10.065Z,1746821170.065 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-05-09T20:06:10.065Z,1746821170.065 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-05-09T20:06:10.066Z,1746821170.066 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-05-09T20:06:10.067Z,1746821170.067 [MissionManager](INFO): Uninitializing Mission Default
2025-05-09T20:06:10.069Z,1746821170.069 [MissionManager](INFO): Uninitializing Mission Startup
2025-05-09T20:06:10.071Z,1746821170.071 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-05-09T20:06:10.071Z,1746821170.071 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-05-09T20:06:10.071Z,1746821170.071 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-05-09T20:06:10.072Z,1746821170.072 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-05-09T20:06:10.072Z,1746821170.072 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-05-09T20:06:10.072Z,1746821170.072 [BuoyancyServo](INFO): Powering down
2025-05-09T20:06:10.202Z,1746821170.202 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-05-09T20:06:10.203Z,1746821170.203 [ElevatorServo](INFO): Powering down
2025-05-09T20:06:10.203Z,1746821170.203 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-05-09T20:06:10.204Z,1746821170.204 [MassServo](INFO): Powering down
2025-05-09T20:06:10.204Z,1746821170.204 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-09T20:06:10.204Z,1746821170.204 [RudderServo](INFO): Powering down
2025-05-09T20:06:10.205Z,1746821170.205 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-05-09T20:06:10.205Z,1746821170.205 [ThrusterHE](INFO): Powering down
2025-05-09T20:06:10.206Z,1746821170.206 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-05-09T20:06:10.207Z,1746821170.207 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-05-09T20:06:10.207Z,1746821170.207 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-05-09T20:06:10.207Z,1746821170.207 [CBIT](DEBUG): Powering off loads.
2025-05-09T20:06:10.218Z,1746821170.218 [CBIT](DEBUG): Disabling WDT.
2025-05-09T20:06:10.230Z,1746821170.230 [CBIT](DEBUG): Opening all GF detection circuits.
2025-05-09T20:06:10.231Z,1746821170.231 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.272Z,1746821170.272 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.275Z,1746821170.275 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.282Z,1746821170.282 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.293Z,1746821170.293 [DATMMP ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.335Z,1746821170.335 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.628Z,1746821170.628 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.633Z,1746821170.633 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.655Z,1746821170.655 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-09T20:06:10.702Z,1746821170.702 [logger ThreadHandler](INFO): Thread cancelled.