2025-04-17T00:18:50.231Z,1744849130.231 [Supervisor](DEBUG): Initializing supervisor.
2025-04-17T00:18:50.263Z,1744849130.263 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-04-17T00:18:50.264Z,1744849130.264 [SyncHandler](INFO): Protected caller Thread ID is 5259
2025-04-17T00:18:50.264Z,1744849130.264 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-04-17T00:18:50.282Z,1744849130.282 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-04-17T00:18:50.283Z,1744849130.283 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5260
2025-04-17T00:18:50.299Z,1744849130.299 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-04-17T00:18:50.379Z,1744849130.379 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-04-17T00:18:50.394Z,1744849130.394 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-04-17T00:18:50.395Z,1744849130.395 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5261
2025-04-17T00:18:50.399Z,1744849130.399 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-04-17T00:18:50.414Z,1744849130.414 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-04-17T00:18:50.415Z,1744849130.415 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5262
2025-04-17T00:18:50.417Z,1744849130.417 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-04-17T00:18:50.430Z,1744849130.430 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-04-17T00:18:50.431Z,1744849130.431 [logger ThreadHandler](INFO): Protected caller Thread ID is 5263
2025-04-17T00:18:50.447Z,1744849130.447 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-04-17T00:18:50.447Z,1744849130.447 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-04-17T00:18:50.449Z,1744849130.449 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-04-17T00:18:53.080Z,1744849133.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-04-17T00:18:53.080Z,1744849133.080 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-04-17T00:18:53.971Z,1744849133.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-04-17T00:18:53.972Z,1744849133.972 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-04-17T00:18:54.255Z,1744849134.255 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-04-17T00:18:55.302Z,1744849135.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-04-17T00:18:55.302Z,1744849135.302 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-04-17T00:18:55.576Z,1744849135.576 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-04-17T00:18:55.906Z,1744849135.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-04-17T00:18:55.906Z,1744849135.906 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-04-17T00:18:57.634Z,1744849137.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-04-17T00:18:57.635Z,1744849137.635 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-04-17T00:18:58.184Z,1744849138.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-04-17T00:18:58.185Z,1744849138.185 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-04-17T00:19:00.414Z,1744849140.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-04-17T00:19:00.415Z,1744849140.415 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-04-17T00:19:01.005Z,1744849141.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-04-17T00:19:01.006Z,1744849141.006 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-04-17T00:19:02.792Z,1744849142.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-04-17T00:19:02.793Z,1744849142.793 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-04-17T00:19:03.761Z,1744849143.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-04-17T00:19:03.761Z,1744849143.761 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-04-17T00:19:04.079Z,1744849144.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-04-17T00:19:04.080Z,1744849144.080 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-04-17T00:19:07.299Z,1744849147.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-04-17T00:19:07.300Z,1744849147.300 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-04-17T00:19:08.550Z,1744849148.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-04-17T00:19:08.551Z,1744849148.551 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-04-17T00:19:09.391Z,1744849149.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-04-17T00:19:09.393Z,1744849149.393 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2025-04-17T00:19:09.394Z,1744849149.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2025-04-17T00:19:09.819Z,1744849149.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2025-04-17T00:19:10.084Z,1744849150.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2025-04-17T00:19:10.567Z,1744849150.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2025-04-17T00:19:10.820Z,1744849150.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2025-04-17T00:19:11.756Z,1744849151.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-04-17T00:19:11.756Z,1744849151.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2025-04-17T00:19:12.063Z,1744849152.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2025-04-17T00:19:12.388Z,1744849152.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2025-04-17T00:19:12.812Z,1744849152.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2025-04-17T00:19:13.359Z,1744849153.359 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2025-04-17T00:19:13.678Z,1744849153.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2025-04-17T00:19:13.901Z,1744849153.901 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-04-17T00:19:13.902Z,1744849153.902 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-04-17T00:19:13.913Z,1744849153.913 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-04-17T00:19:13.914Z,1744849153.914 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-04-17T00:19:14.431Z,1744849154.431 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-04-17T00:19:14.431Z,1744849154.431 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-04-17T00:19:14.633Z,1744849154.633 [VerticalControl](DEBUG): Construct VerticalControl.
2025-04-17T00:19:14.928Z,1744849154.928 [VerticalControl] Loaded
2025-04-17T00:19:14.928Z,1744849154.928 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-04-17T00:19:14.947Z,1744849154.947 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-04-17T00:19:15.098Z,1744849155.098 [HorizontalControl] Loaded
2025-04-17T00:19:15.114Z,1744849155.114 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-04-17T00:19:15.116Z,1744849155.116 [SpeedControl](DEBUG): Construct SpeedControl.
2025-04-17T00:19:15.135Z,1744849155.135 [SpeedControl] Loaded
2025-04-17T00:19:15.135Z,1744849155.135 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-04-17T00:19:15.138Z,1744849155.138 [LoopControl](DEBUG): Construct LoopControl.
2025-04-17T00:19:15.155Z,1744849155.155 [LoopControl] Loaded
2025-04-17T00:19:15.155Z,1744849155.155 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-04-17T00:19:15.155Z,1744849155.155 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-04-17T00:19:15.156Z,1744849155.156 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-04-17T00:19:15.667Z,1744849155.667 [BuoyancyServo] Loaded
2025-04-17T00:19:15.667Z,1744849155.667 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-04-17T00:19:15.755Z,1744849155.755 [ElevatorServo] Loaded
2025-04-17T00:19:15.756Z,1744849155.756 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-04-17T00:19:15.828Z,1744849155.828 [MassServo] Loaded
2025-04-17T00:19:15.828Z,1744849155.828 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-04-17T00:19:15.899Z,1744849155.899 [RudderServo] Loaded
2025-04-17T00:19:15.899Z,1744849155.899 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-04-17T00:19:15.949Z,1744849155.949 [ThrusterHE] Loaded
2025-04-17T00:19:15.950Z,1744849155.950 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-04-17T00:19:15.963Z,1744849155.963 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-04-17T00:19:15.963Z,1744849155.963 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-04-17T00:19:16.144Z,1744849156.144 [DepthRateCalculator] Loaded
2025-04-17T00:19:16.144Z,1744849156.144 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-04-17T00:19:16.165Z,1744849156.165 [PitchRateCalculator] Loaded
2025-04-17T00:19:16.165Z,1744849156.165 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-04-17T00:19:16.199Z,1744849156.199 [SpeedCalculator] Loaded
2025-04-17T00:19:16.200Z,1744849156.200 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-04-17T00:19:16.204Z,1744849156.204 [YawRateCalculator] Loaded
2025-04-17T00:19:16.204Z,1744849156.204 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-04-17T00:19:16.263Z,1744849156.263 [ElevatorOffsetCalculator] Loaded
2025-04-17T00:19:16.264Z,1744849156.264 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-04-17T00:19:16.264Z,1744849156.264 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-04-17T00:19:16.265Z,1744849156.265 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-04-17T00:19:16.683Z,1744849156.683 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-04-17T00:19:16.683Z,1744849156.683 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-04-17T00:19:16.723Z,1744849156.723 [NavChart] Loaded
2025-04-17T00:19:16.723Z,1744849156.723 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-04-17T00:19:16.733Z,1744849156.733 [UniversalFixResidualReporter] Loaded
2025-04-17T00:19:16.734Z,1744849156.734 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-04-17T00:19:16.739Z,1744849156.739 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-04-17T00:19:16.739Z,1744849156.739 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-04-17T00:19:17.968Z,1744849157.968 [AHRS_M2] Loaded
2025-04-17T00:19:17.969Z,1744849157.969 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-04-17T00:19:18.156Z,1744849158.156 [BackseatComponent] Loaded
2025-04-17T00:19:18.156Z,1744849158.156 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-04-17T00:19:18.157Z,1744849158.157 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2025-04-17T00:19:18.157Z,1744849158.157 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5347
2025-04-17T00:19:18.169Z,1744849158.169 [LcmUniversalReporter] Loaded
2025-04-17T00:19:18.169Z,1744849158.169 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-04-17T00:19:18.208Z,1744849158.208 [BioacousticsDataBridge] Loaded
2025-04-17T00:19:18.208Z,1744849158.208 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-04-17T00:19:19.462Z,1744849159.462 [BPC1] Loaded
2025-04-17T00:19:19.462Z,1744849159.462 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-04-17T00:19:19.540Z,1744849159.540 [DataOverHttps] Loaded
2025-04-17T00:19:19.541Z,1744849159.541 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-04-17T00:19:19.542Z,1744849159.542 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2025-04-17T00:19:19.543Z,1744849159.543 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5348
2025-04-17T00:19:19.569Z,1744849159.569 [Depth_Keller] Loaded
2025-04-17T00:19:19.569Z,1744849159.569 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-04-17T00:19:19.574Z,1744849159.574 [DropWeight] Loaded
2025-04-17T00:19:19.574Z,1744849159.574 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-04-17T00:19:19.694Z,1744849159.694 [NAL9602] Loaded
2025-04-17T00:19:19.694Z,1744849159.694 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-04-17T00:19:19.740Z,1744849159.740 [Onboard] Loaded
2025-04-17T00:19:19.741Z,1744849159.741 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-04-17T00:19:19.742Z,1744849159.742 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2025-04-17T00:19:19.742Z,1744849159.742 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5349
2025-04-17T00:19:19.758Z,1744849159.758 [Power24vConverter] Loaded
2025-04-17T00:19:19.758Z,1744849159.758 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-04-17T00:19:19.775Z,1744849159.775 [Radio_Surface] Loaded
2025-04-17T00:19:19.775Z,1744849159.775 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-04-17T00:19:19.776Z,1744849159.776 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409CB4E0
2025-04-17T00:19:19.776Z,1744849159.776 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5350
2025-04-17T00:19:19.793Z,1744849159.793 [Sonardyne_Nano] Loaded
2025-04-17T00:19:19.793Z,1744849159.793 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-04-17T00:19:19.794Z,1744849159.794 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-04-17T00:19:19.794Z,1744849159.794 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-04-17T00:19:19.869Z,1744849159.869 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-04-17T00:19:19.870Z,1744849159.870 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-04-17T00:19:20.093Z,1744849160.093 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-04-17T00:19:20.093Z,1744849160.093 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-04-17T00:19:20.135Z,1744849160.135 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-04-17T00:19:20.136Z,1744849160.136 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-04-17T00:19:20.328Z,1744849160.328 [CTD_Seabird] Loaded
2025-04-17T00:19:20.328Z,1744849160.328 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-04-17T00:19:20.330Z,1744849160.330 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2025-04-17T00:19:20.330Z,1744849160.330 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5351
2025-04-17T00:19:20.355Z,1744849160.355 [PAR_Licor] Loaded
2025-04-17T00:19:20.355Z,1744849160.355 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-04-17T00:19:20.404Z,1744849160.404 [WetLabsBB2FL] Loaded
2025-04-17T00:19:20.404Z,1744849160.404 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-04-17T00:19:20.405Z,1744849160.405 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0
2025-04-17T00:19:20.405Z,1744849160.405 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5352
2025-04-17T00:19:20.406Z,1744849160.406 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-04-17T00:19:20.407Z,1744849160.407 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-04-17T00:19:20.820Z,1744849160.820 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-04-17T00:19:20.821Z,1744849160.821 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-04-17T00:19:21.124Z,1744849161.124 [SBIT](DEBUG): Construct Startup Built In Test.
2025-04-17T00:19:21.146Z,1744849161.146 [SBIT] Loaded
2025-04-17T00:19:21.146Z,1744849161.146 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-04-17T00:19:21.149Z,1744849161.149 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-04-17T00:19:21.191Z,1744849161.191 [IBIT] Loaded
2025-04-17T00:19:21.191Z,1744849161.191 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-04-17T00:19:21.197Z,1744849161.197 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-04-17T00:19:21.502Z,1744849161.502 [CBIT] Loaded
2025-04-17T00:19:21.503Z,1744849161.503 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-04-17T00:19:21.503Z,1744849161.503 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-04-17T00:19:21.519Z,1744849161.519 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-04-17T00:19:21.522Z,1744849161.522 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-04-17T00:19:21.551Z,1744849161.551 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-04-17T00:19:21.558Z,1744849161.558 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3B4E0
2025-04-17T00:19:21.559Z,1744849161.559 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5353
2025-04-17T00:19:21.563Z,1744849161.563 [Supervisor](INFO): Main Thread ID is 5258
2025-04-17T00:19:21.563Z,1744849161.563 [Supervisor](DEBUG): Running supervisor.
2025-04-17T00:19:21.564Z,1744849161.564 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5354
2025-04-17T00:19:21.565Z,1744849161.565 [CommandExec](INFO): Initializing the command executive.
2025-04-17T00:19:21.583Z,1744849161.583 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5355
2025-04-17T00:19:21.595Z,1744849161.595 [controlThread ThreadHandler](INFO): Handler Thread ID is 5356
2025-04-17T00:19:21.595Z,1744849161.595 [controlThread](DEBUG): Initializing ControlThread
2025-04-17T00:19:21.596Z,1744849161.596 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-04-17T00:19:21.597Z,1744849161.597 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-04-17T00:19:21.598Z,1744849161.598 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-04-17T00:19:21.599Z,1744849161.599 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-04-17T00:19:21.600Z,1744849161.600 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-04-17T00:19:21.600Z,1744849161.600 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-04-17T00:19:21.600Z,1744849161.600 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-04-17T00:19:21.601Z,1744849161.601 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-04-17T00:19:21.601Z,1744849161.601 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-04-17T00:19:21.602Z,1744849161.602 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-04-17T00:19:21.602Z,1744849161.602 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-04-17T00:19:21.619Z,1744849161.619 [SBIT](INFO): Initialize SBIT Component.
2025-04-17T00:19:21.619Z,1744849161.619 [SBIT](IMPORTANT): git: 2024-12-16_A-129-gdfc4b4cb1
2025-04-17T00:19:21.619Z,1744849161.619 [SBIT](INFO): git hash: dfc4b4cb1f964e05e7aa12596fa9f50e33a9d75d
2025-04-17T00:19:21.620Z,1744849161.620 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-04-17T00:19:21.621Z,1744849161.621 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-04-17T00:19:21.627Z,1744849161.627 [logger ThreadHandler](INFO): Handler Thread ID is 5357
2025-04-17T00:19:21.682Z,1744849161.682 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2025-04-17T00:19:21.683Z,1744849161.683 [IBIT](INFO): Initialize IBIT Component.
2025-04-17T00:19:21.684Z,1744849161.684 [CBIT](DEBUG): Initialize CBIT Component.
2025-04-17T00:19:21.698Z,1744849161.698 [CBIT](DEBUG): Initialized mux pins.
2025-04-17T00:19:21.698Z,1744849161.698 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-04-17T00:19:21.699Z,1744849161.699 [CBIT](DEBUG): Initializing the watchdog timer.
2025-04-17T00:19:21.703Z,1744849161.703 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5358
2025-04-17T00:19:21.705Z,1744849161.705 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5359
2025-04-17T00:19:21.706Z,1744849161.706 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-04-17T00:19:21.722Z,1744849161.722 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-04-17T00:19:21.723Z,1744849161.723 [CBIT](DEBUG): Initializing heartbeat.
2025-04-17T00:19:21.724Z,1744849161.724 [Onboard ThreadHandler](INFO): Handler Thread ID is 5360
2025-04-17T00:19:21.763Z,1744849161.763 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5361
2025-04-17T00:19:21.787Z,1744849161.787 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5362
2025-04-17T00:19:21.788Z,1744849161.788 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-17T00:19:21.794Z,1744849161.794 [CBIT](DEBUG): Deactivating GF circuits.
2025-04-17T00:19:21.795Z,1744849161.795 [CBIT](DEBUG): Deactivating emergency mode.
2025-04-17T00:19:21.825Z,1744849161.825 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5364
2025-04-17T00:19:21.834Z,1744849161.834 [CBIT](DEBUG): Backplane powered.
2025-04-17T00:19:21.835Z,1744849161.835 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-04-17T00:19:21.836Z,1744849161.836 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-04-17T00:19:21.836Z,1744849161.836 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-04-17T00:19:21.837Z,1744849161.837 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-04-17T00:19:21.855Z,1744849161.855 [WetLabsBB2FL](INFO): Powering up
2025-04-17T00:19:21.859Z,1744849161.859 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5366
2025-04-17T00:19:21.863Z,1744849161.863 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2025-04-17T00:19:21.863Z,1744849161.863 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2025-04-17T00:19:21.864Z,1744849161.864 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2025-04-17T00:19:21.864Z,1744849161.864 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2025-04-17T00:19:21.864Z,1744849161.864 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2025-04-17T00:19:21.864Z,1744849161.864 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2025-04-17T00:19:21.864Z,1744849161.864 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2025-04-17T00:19:21.864Z,1744849161.864 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2025-04-17T00:19:21.865Z,1744849161.865 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2025-04-17T00:19:21.865Z,1744849161.865 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2025-04-17T00:19:21.865Z,1744849161.865 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2025-04-17T00:19:21.865Z,1744849161.865 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2025-04-17T00:19:21.865Z,1744849161.865 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2025-04-17T00:19:21.865Z,1744849161.865 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2025-04-17T00:19:21.866Z,1744849161.866 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2025-04-17T00:19:21.866Z,1744849161.866 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2025-04-17T00:19:21.866Z,1744849161.866 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2025-04-17T00:19:21.867Z,1744849161.867 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2025-04-17T00:19:21.867Z,1744849161.867 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2025-04-17T00:19:21.867Z,1744849161.867 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2025-04-17T00:19:21.909Z,1744849161.909 [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-04-17T00:19:21.917Z,1744849161.917 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-17T00:19:21.983Z,1744849161.983 [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-04-17T00:19:21.984Z,1744849161.984 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-04-17T00:19:21.984Z,1744849161.984 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-04-17T00:19:21.985Z,1744849161.985 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-04-17T00:19:22.167Z,1744849162.167 [Radio_Surface](INFO): Powering up
2025-04-17T00:19:22.494Z,1744849162.494 [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-04-17T00:19:22.541Z,1744849162.541 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-04-17T00:19:22.542Z,1744849162.542 [Default:A.Wait](DEBUG): Construct Wait.
2025-04-17T00:19:22.552Z,1744849162.552 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-17T00:19:22.614Z,1744849162.614 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-04-17T00:19:22.625Z,1744849162.625 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-04-17T00:19:22.660Z,1744849162.660 [Default:E.Execute](DEBUG): Construct Execute.
2025-04-17T00:19:22.675Z,1744849162.675 [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-04-17T00:19:22.680Z,1744849162.680 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-17T00:19:22.728Z,1744849162.728 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-17T00:19:22.817Z,1744849162.817 [Power24vConverter](INFO): Powering up.
2025-04-17T00:19:22.817Z,1744849162.817 [Sonardyne_Nano](INFO): Initializing.
2025-04-17T00:19:22.899Z,1744849162.899 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:22.924Z,1744849162.924 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-17T00:19:22.925Z,1744849162.925 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:22.925Z,1744849162.925 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-17T00:19:22.926Z,1744849162.926 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:22.950Z,1744849162.950 [MassServo](DEBUG): Initializing MassServo.
2025-04-17T00:19:22.951Z,1744849162.951 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:22.967Z,1744849162.967 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-17T00:19:22.968Z,1744849162.968 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:22.983Z,1744849162.983 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-17T00:19:23.215Z,1744849163.215 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-04-17T00:19:23.215Z,1744849163.215 [DropWeight] Hardware Fault, FailCount= 1
2025-04-17T00:19:23.215Z,1744849163.215 [DropWeight](ERROR): Hardware Fault
2025-04-17T00:19:23.279Z,1744849163.279 [CommandExec](FAULT): Scheduling is paused
2025-04-17T00:19:23.280Z,1744849163.280 [CBIT](INFO): Critical error at 20250417T001923
2025-04-17T00:19:23.280Z,1744849163.280 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-04-17T00:19:23.307Z,1744849163.307 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-17T00:19:23.307Z,1744849163.307 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-17T00:19:23.535Z,1744849163.535 [CBIT](INFO): Critical error at 20250417T001923
2025-04-17T00:19:24.439Z,1744849164.439 [WetLabsBB2FL](INFO): Powering down
2025-04-17T00:19:25.479Z,1744849165.479 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2025-04-17T00:19:25.479Z,1744849165.479 [MassServo] Communications Fault, FailCount= 1
2025-04-17T00:19:25.479Z,1744849165.479 [MassServo](ERROR): Communications Fault
2025-04-17T00:19:25.483Z,1744849165.483 [CBIT](ERROR): Communications Fault in component: MassServo
2025-04-17T00:19:25.631Z,1744849165.631 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-17T00:19:25.631Z,1744849165.631 [MassServo](INFO): Powering down
2025-04-17T00:19:26.675Z,1744849166.675 [CBIT](INFO): Clearing failed state for component MassServo
2025-04-17T00:19:26.676Z,1744849166.676 [MassServo] No Fault, FailCount= 1
2025-04-17T00:19:26.859Z,1744849166.859 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:26.860Z,1744849166.860 [MassServo](DEBUG): Initializing MassServo.
2025-04-17T00:19:29.558Z,1744849169.558 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2025-04-17T00:19:29.558Z,1744849169.558 [MassServo] Communications Fault, FailCount= 2
2025-04-17T00:19:29.559Z,1744849169.559 [MassServo](ERROR): Communications Fault
2025-04-17T00:19:29.682Z,1744849169.682 [CBIT](ERROR): Communications Fault in component: MassServo
2025-04-17T00:19:29.809Z,1744849169.809 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-17T00:19:29.809Z,1744849169.809 [MassServo](INFO): Powering down
2025-04-17T00:19:30.629Z,1744849170.629 [CBIT](INFO): Clearing failed state for component MassServo
2025-04-17T00:19:30.629Z,1744849170.629 [MassServo] No Fault, FailCount= 2
2025-04-17T00:19:31.002Z,1744849171.002 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:31.018Z,1744849171.018 [MassServo](DEBUG): Initializing MassServo.
2025-04-17T00:19:33.819Z,1744849173.819 [MassServo](FAULT): Mass initialization uart error:serial timeout
2025-04-17T00:19:33.819Z,1744849173.819 [MassServo] Communications Fault, FailCount= 3
2025-04-17T00:19:33.819Z,1744849173.819 [MassServo](ERROR): Communications Fault
2025-04-17T00:19:33.823Z,1744849173.823 [CBIT](ERROR): Communications Fault in component: MassServo
2025-04-17T00:19:33.929Z,1744849173.929 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-17T00:19:33.929Z,1744849173.929 [MassServo](INFO): Powering down
2025-04-17T00:19:34.704Z,1744849174.704 [CBIT](INFO): Clearing failed state for component MassServo
2025-04-17T00:19:34.705Z,1744849174.705 [MassServo] No Fault, FailCount= 3
2025-04-17T00:19:35.177Z,1744849175.177 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:35.177Z,1744849175.177 [MassServo](DEBUG): Initializing MassServo.
2025-04-17T00:19:38.799Z,1744849178.799 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: no error
2025-04-17T00:19:38.799Z,1744849178.799 [MassServo] Hardware Fault, FailCount= 4
2025-04-17T00:19:38.799Z,1744849178.799 [MassServo](ERROR): Hardware Fault
2025-04-17T00:19:39.102Z,1744849179.102 [MassServo](ERROR): getPosition uart error serial timeout
2025-04-17T00:19:39.103Z,1744849179.103 [MassServo](FAULT): Invalid EZ Servo response:""
2025-04-17T00:19:39.103Z,1744849179.103 [MassServo] Communications Fault, FailCount= 4
2025-04-17T00:19:39.103Z,1744849179.103 [MassServo](ERROR): Communications Fault
2025-04-17T00:19:39.108Z,1744849179.108 [CBIT](ERROR): Communications Fault in component: MassServo
2025-04-17T00:19:39.203Z,1744849179.203 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-17T00:19:39.203Z,1744849179.203 [MassServo](INFO): Powering down
2025-04-17T00:19:40.065Z,1744849180.065 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:40.066Z,1744849180.066 [MassServo](DEBUG): Initializing MassServo.
2025-04-17T00:19:40.132Z,1744849180.132 [CBIT](INFO): Clearing failed state for component MassServo
2025-04-17T00:19:40.132Z,1744849180.132 [MassServo] No Fault, FailCount= 4
2025-04-17T00:19:40.739Z,1744849180.739 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2025-04-17T00:19:40.909Z,1744849180.909 [CBIT](CRITICAL): Run-time exception #7
2025-04-17T00:19:40.909Z,1744849180.909 [CBIT] Software Fault, FailCount= 1
2025-04-17T00:19:40.909Z,1744849180.909 [CBIT](ERROR): Software Fault
2025-04-17T00:19:40.912Z,1744849180.912 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-04-17T00:19:40.913Z,1744849180.913 [SyncHandler](INFO): Protected caller Thread ID is 5383
2025-04-17T00:19:41.297Z,1744849181.297 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-04-17T00:19:41.330Z,1744849181.330 [CBIT](DEBUG): Powering off loads.
2025-04-17T00:19:41.342Z,1744849181.342 [CBIT](DEBUG): Disabling WDT.
2025-04-17T00:19:41.354Z,1744849181.354 [CBIT](DEBUG): Opening all GF detection circuits.
2025-04-17T00:19:42.031Z,1744849182.031 [SBIT](IMPORTANT): Beginning Startup BIT
2025-04-17T00:19:42.710Z,1744849182.710 [ElevatorServo](ERROR): getPosition uart error serial timeout
2025-04-17T00:19:42.711Z,1744849182.711 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2025-04-17T00:19:42.711Z,1744849182.711 [ElevatorServo] Communications Fault, FailCount= 1
2025-04-17T00:19:42.711Z,1744849182.711 [ElevatorServo](ERROR): Communications Fault
2025-04-17T00:19:43.022Z,1744849183.022 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2025-04-17T00:19:43.022Z,1744849183.022 [MassServo] Communications Fault, FailCount= 5
2025-04-17T00:19:43.022Z,1744849183.022 [MassServo](ERROR): Communications Fault
2025-04-17T00:19:43.095Z,1744849183.095 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-17T00:19:43.095Z,1744849183.095 [ElevatorServo](INFO): Powering down
2025-04-17T00:19:43.096Z,1744849183.096 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-17T00:19:43.096Z,1744849183.096 [MassServo](INFO): Powering down
2025-04-17T00:19:43.888Z,1744849183.888 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:44.007Z,1744849184.007 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-17T00:19:44.008Z,1744849184.008 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:44.127Z,1744849184.127 [MassServo](DEBUG): Initializing MassServo.
2025-04-17T00:19:44.430Z,1744849184.430 [RudderServo](ERROR): getPosition uart error serial timeout
2025-04-17T00:19:44.430Z,1744849184.430 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-04-17T00:19:44.431Z,1744849184.431 [RudderServo] Communications Fault, FailCount= 1
2025-04-17T00:19:44.431Z,1744849184.431 [RudderServo](ERROR): Communications Fault
2025-04-17T00:19:44.500Z,1744849184.500 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-17T00:19:44.500Z,1744849184.500 [RudderServo](INFO): Powering down
2025-04-17T00:19:45.376Z,1744849185.376 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-17T00:19:45.495Z,1744849185.495 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-17T00:19:49.704Z,1744849189.704 [NAL9602](INFO): Powering up NAL9602
2025-04-17T00:19:50.910Z,1744849190.910 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-04-17T00:19:50.910Z,1744849190.910 [AHRS_M2] Data Fault, FailCount= 1
2025-04-17T00:19:50.910Z,1744849190.910 [AHRS_M2](ERROR): Data Fault
2025-04-17T00:19:51.304Z,1744849191.304 [AHRS_M2](INFO): Powering down
2025-04-17T00:19:55.361Z,1744849195.361 [SBIT](ERROR): Could not read elevatorAngleReader_.
2025-04-17T00:19:55.361Z,1744849195.361 [SBIT](ERROR): Could not read rudderAngleReader_.
2025-04-17T00:19:55.361Z,1744849195.361 [SBIT](ERROR): Could not read massPosReader_.
2025-04-17T00:19:55.428Z,1744849195.428 [CommandExec](IMPORTANT): got command restart application
2025-04-17T00:19:56.430Z,1744849196.430 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:19:56.430Z,1744849196.430 [CommandExec](INFO): Uninitializing the command executive.
2025-04-17T00:19:56.431Z,1744849196.431 [CommandExec](INFO): Uninitializing the command scheduler.
2025-04-17T00:19:56.431Z,1744849196.431 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:56.626Z,1744849196.626 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-04-17T00:19:56.626Z,1744849196.626 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-04-17T00:19:56.626Z,1744849196.626 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:56.627Z,1744849196.627 [NavChartDb](INFO): Join timeout helper Thread ID is 5389
2025-04-17T00:19:56.870Z,1744849196.870 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:19:56.871Z,1744849196.871 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:56.887Z,1744849196.887 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-04-17T00:19:56.887Z,1744849196.887 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:56.888Z,1744849196.888 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5390
2025-04-17T00:19:57.107Z,1744849197.107 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:19:57.107Z,1744849197.107 [WetLabsBB2FL](INFO): Powering down
2025-04-17T00:19:57.108Z,1744849197.108 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:57.126Z,1744849197.126 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-04-17T00:19:57.126Z,1744849197.126 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:57.127Z,1744849197.127 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5391
2025-04-17T00:19:57.970Z,1744849197.970 [CTD_Seabird](INFO): Powering down
2025-04-17T00:19:57.983Z,1744849197.983 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:19:57.984Z,1744849197.984 [CTD_Seabird](INFO): Powering down
2025-04-17T00:19:57.998Z,1744849197.998 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:58.014Z,1744849198.014 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-04-17T00:19:58.014Z,1744849198.014 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:58.015Z,1744849198.015 [Radio_Surface](INFO): Join timeout helper Thread ID is 5392
2025-04-17T00:19:58.138Z,1744849198.138 [Radio_Surface](INFO): Powering down
2025-04-17T00:19:58.139Z,1744849198.139 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:19:58.140Z,1744849198.140 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:58.150Z,1744849198.150 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-04-17T00:19:58.150Z,1744849198.150 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:19:58.151Z,1744849198.151 [Onboard](INFO): Join timeout helper Thread ID is 5393
2025-04-17T00:20:01.754Z,1744849201.754 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:20:01.755Z,1744849201.755 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.771Z,1744849201.771 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-04-17T00:20:01.771Z,1744849201.771 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.772Z,1744849201.772 [DataOverHttps](INFO): Join timeout helper Thread ID is 5394
2025-04-17T00:20:01.810Z,1744849201.810 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:20:01.811Z,1744849201.811 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.812Z,1744849201.812 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-04-17T00:20:01.812Z,1744849201.812 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.813Z,1744849201.813 [BackseatComponent](INFO): Join timeout helper Thread ID is 5395
2025-04-17T00:20:01.923Z,1744849201.923 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:20:01.923Z,1744849201.923 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.936Z,1744849201.936 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-04-17T00:20:01.936Z,1744849201.936 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.936Z,1744849201.936 [logger](INFO): Join timeout helper Thread ID is 5396
2025-04-17T00:20:01.945Z,1744849201.945 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:20:01.945Z,1744849201.945 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.946Z,1744849201.946 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-04-17T00:20:01.947Z,1744849201.947 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:01.947Z,1744849201.947 [CommandLine](INFO): Join timeout helper Thread ID is 5397
2025-04-17T00:20:02.023Z,1744849202.023 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:20:02.023Z,1744849202.023 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.027Z,1744849202.027 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-04-17T00:20:02.027Z,1744849202.027 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.028Z,1744849202.028 [CommandExec](INFO): Join timeout helper Thread ID is 5398
2025-04-17T00:20:02.029Z,1744849202.029 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-04-17T00:20:02.029Z,1744849202.029 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.029Z,1744849202.029 [controlThread](INFO): Join timeout helper Thread ID is 5399
2025-04-17T00:20:02.207Z,1744849202.207 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-17T00:20:02.207Z,1744849202.207 [controlThread](DEBUG): Uninitializing ControlThread
2025-04-17T00:20:02.207Z,1744849202.207 [AHRS_M2](INFO): Powering down
2025-04-17T00:20:02.210Z,1744849202.210 [NAL9602](INFO): Powering down
2025-04-17T00:20:02.211Z,1744849202.211 [Sonardyne_Nano](INFO): Powering down
2025-04-17T00:20:02.331Z,1744849202.331 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-04-17T00:20:02.332Z,1744849202.332 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-04-17T00:20:02.333Z,1744849202.333 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-04-17T00:20:02.333Z,1744849202.333 [MissionManager](INFO): Uninitializing Mission Default
2025-04-17T00:20:02.336Z,1744849202.336 [MissionManager](INFO): Uninitializing Mission Startup
2025-04-17T00:20:02.337Z,1744849202.337 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-04-17T00:20:02.337Z,1744849202.337 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-04-17T00:20:02.338Z,1744849202.338 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-04-17T00:20:02.338Z,1744849202.338 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-04-17T00:20:02.338Z,1744849202.338 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-17T00:20:02.339Z,1744849202.339 [BuoyancyServo](INFO): Powering down
2025-04-17T00:20:02.350Z,1744849202.350 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-17T00:20:02.350Z,1744849202.350 [ElevatorServo](INFO): Powering down
2025-04-17T00:20:02.351Z,1744849202.351 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-17T00:20:02.351Z,1744849202.351 [MassServo](INFO): Powering down
2025-04-17T00:20:02.352Z,1744849202.352 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-17T00:20:02.352Z,1744849202.352 [RudderServo](INFO): Powering down
2025-04-17T00:20:02.353Z,1744849202.353 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-17T00:20:02.353Z,1744849202.353 [ThrusterHE](INFO): Powering down
2025-04-17T00:20:02.354Z,1744849202.354 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-04-17T00:20:02.354Z,1744849202.354 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-04-17T00:20:02.355Z,1744849202.355 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-04-17T00:20:02.355Z,1744849202.355 [CBIT](DEBUG): Powering off loads.
2025-04-17T00:20:02.366Z,1744849202.366 [CBIT](DEBUG): Disabling WDT.
2025-04-17T00:20:02.378Z,1744849202.378 [CBIT](DEBUG): Opening all GF detection circuits.
2025-04-17T00:20:02.379Z,1744849202.379 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.787Z,1744849202.787 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.790Z,1744849202.790 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.795Z,1744849202.795 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.838Z,1744849202.838 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.879Z,1744849202.879 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.883Z,1744849202.883 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.916Z,1744849202.916 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-17T00:20:02.965Z,1744849202.965 [logger ThreadHandler](INFO): Thread cancelled.