2025-04-04T20:50:58.963Z,1743799858.963 [Supervisor](DEBUG): Initializing supervisor.
2025-04-04T20:50:58.968Z,1743799858.968 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-04-04T20:50:58.968Z,1743799858.968 [SyncHandler](INFO): Protected caller Thread ID is 850
2025-04-04T20:50:58.969Z,1743799858.969 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-04-04T20:50:58.970Z,1743799858.970 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-04-04T20:50:58.970Z,1743799858.970 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 851
2025-04-04T20:50:58.974Z,1743799858.974 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-04-04T20:50:58.996Z,1743799858.996 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-04-04T20:50:58.997Z,1743799858.997 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-04-04T20:50:58.997Z,1743799858.997 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 852
2025-04-04T20:50:59.002Z,1743799859.002 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-04-04T20:50:59.003Z,1743799859.003 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-04-04T20:50:59.003Z,1743799859.003 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 853
2025-04-04T20:50:59.005Z,1743799859.005 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-04-04T20:50:59.006Z,1743799859.006 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-04-04T20:50:59.007Z,1743799859.007 [logger ThreadHandler](INFO): Protected caller Thread ID is 854
2025-04-04T20:50:59.011Z,1743799859.011 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-04-04T20:50:59.011Z,1743799859.011 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-04-04T20:50:59.015Z,1743799859.015 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-04-04T20:50:59.367Z,1743799859.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-04-04T20:50:59.369Z,1743799859.369 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-04-04T20:50:59.648Z,1743799859.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-04-04T20:50:59.649Z,1743799859.649 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-04-04T20:51:00.163Z,1743799860.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-04-04T20:51:00.165Z,1743799860.165 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-04-04T20:51:00.410Z,1743799860.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-04-04T20:51:00.412Z,1743799860.412 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-04-04T20:51:00.648Z,1743799860.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-04-04T20:51:00.649Z,1743799860.649 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-04-04T20:51:01.490Z,1743799861.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-04-04T20:51:01.490Z,1743799861.490 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-04-04T20:51:01.851Z,1743799861.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-04-04T20:51:01.852Z,1743799861.852 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-04-04T20:51:01.974Z,1743799861.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-04-04T20:51:01.975Z,1743799861.975 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-04-04T20:51:02.070Z,1743799862.070 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-04-04T20:51:02.720Z,1743799862.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-04-04T20:51:02.721Z,1743799862.721 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-04-04T20:51:02.829Z,1743799862.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-04-04T20:51:02.830Z,1743799862.830 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-04-04T20:51:03.066Z,1743799863.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-04-04T20:51:03.067Z,1743799863.067 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-04-04T20:51:03.216Z,1743799863.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-04-04T20:51:03.217Z,1743799863.217 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-04-04T20:51:03.308Z,1743799863.308 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-04-04T20:51:03.754Z,1743799863.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-04-04T20:51:03.755Z,1743799863.755 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-04-04T20:51:04.337Z,1743799864.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-04-04T20:51:04.339Z,1743799864.339 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/
2025-04-04T20:51:04.342Z,1743799864.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg
2025-04-04T20:51:04.488Z,1743799864.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg
2025-04-04T20:51:04.769Z,1743799864.769 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-04-04T20:51:04.770Z,1743799864.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg
2025-04-04T20:51:04.883Z,1743799864.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg
2025-04-04T20:51:04.918Z,1743799864.918 [Config/vehicle](ERROR): Unterminated quote: "/dev/loadC3;
2025-04-04T20:51:04.990Z,1743799864.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg
2025-04-04T20:51:05.111Z,1743799865.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg
2025-04-04T20:51:05.254Z,1743799865.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg
2025-04-04T20:51:05.357Z,1743799865.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg
2025-04-04T20:51:05.476Z,1743799865.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg
2025-04-04T20:51:05.570Z,1743799865.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg
2025-04-04T20:51:05.682Z,1743799865.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg
2025-04-04T20:51:05.778Z,1743799865.778 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-04-04T20:51:05.782Z,1743799865.782 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-04-04T20:51:05.799Z,1743799865.799 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-04-04T20:51:05.799Z,1743799865.799 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-04-04T20:51:05.943Z,1743799865.943 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-04-04T20:51:05.944Z,1743799865.944 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-04-04T20:51:05.984Z,1743799865.984 [DeadReckonWithRespectToSeafloor] Loaded
2025-04-04T20:51:05.985Z,1743799865.985 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2025-04-04T20:51:05.999Z,1743799865.999 [NavChart] Loaded
2025-04-04T20:51:05.000Z,1743799866.000 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-04-04T20:51:06.007Z,1743799866.007 [UniversalFixResidualReporter] Loaded
2025-04-04T20:51:06.007Z,1743799866.007 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-04-04T20:51:06.007Z,1743799866.007 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-04-04T20:51:06.008Z,1743799866.008 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-04-04T20:51:06.828Z,1743799866.828 [AHRS_M2] Loaded
2025-04-04T20:51:06.828Z,1743799866.828 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-04-04T20:51:07.084Z,1743799867.084 [BackseatComponent] Loaded
2025-04-04T20:51:07.084Z,1743799867.084 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-04-04T20:51:07.085Z,1743799867.085 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085D4E0
2025-04-04T20:51:07.086Z,1743799867.086 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 936
2025-04-04T20:51:07.088Z,1743799867.088 [LcmUniversalReporter] Loaded
2025-04-04T20:51:07.089Z,1743799867.089 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-04-04T20:51:07.902Z,1743799867.902 [BPC1] Loaded
2025-04-04T20:51:07.902Z,1743799867.902 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-04-04T20:51:08.001Z,1743799868.001 [DAT] Loaded
2025-04-04T20:51:08.001Z,1743799868.001 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-04-04T20:51:08.002Z,1743799868.002 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088D4E0
2025-04-04T20:51:08.003Z,1743799868.003 [DAT ThreadHandler](INFO): Protected caller Thread ID is 937
2025-04-04T20:51:08.145Z,1743799868.145 [DataOverHttps] Loaded
2025-04-04T20:51:08.145Z,1743799868.145 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-04-04T20:51:08.146Z,1743799868.146 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0
2025-04-04T20:51:08.146Z,1743799868.146 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 938
2025-04-04T20:51:08.169Z,1743799868.169 [DepthKeller33X] Loaded
2025-04-04T20:51:08.169Z,1743799868.169 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread.
2025-04-04T20:51:08.174Z,1743799868.174 [DropWeight] Loaded
2025-04-04T20:51:08.174Z,1743799868.174 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-04-04T20:51:08.239Z,1743799868.239 [NAL9602] Loaded
2025-04-04T20:51:08.239Z,1743799868.239 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-04-04T20:51:08.270Z,1743799868.270 [Onboard] Loaded
2025-04-04T20:51:08.270Z,1743799868.270 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-04-04T20:51:08.271Z,1743799868.271 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2025-04-04T20:51:08.271Z,1743799868.271 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 939
2025-04-04T20:51:08.286Z,1743799868.286 [Power24vConverter] Loaded
2025-04-04T20:51:08.286Z,1743799868.286 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-04-04T20:51:08.303Z,1743799868.303 [Radio_Surface] Loaded
2025-04-04T20:51:08.304Z,1743799868.304 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-04-04T20:51:08.305Z,1743799868.305 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0
2025-04-04T20:51:08.305Z,1743799868.305 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 940
2025-04-04T20:51:08.306Z,1743799868.306 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-04-04T20:51:08.307Z,1743799868.307 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-04-04T20:51:08.544Z,1743799868.544 [DepthRateCalculator] Loaded
2025-04-04T20:51:08.544Z,1743799868.544 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-04-04T20:51:08.561Z,1743799868.561 [PitchRateCalculator] Loaded
2025-04-04T20:51:08.562Z,1743799868.562 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-04-04T20:51:08.595Z,1743799868.595 [SpeedCalculator] Loaded
2025-04-04T20:51:08.595Z,1743799868.595 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-04-04T20:51:08.600Z,1743799868.600 [YawRateCalculator] Loaded
2025-04-04T20:51:08.600Z,1743799868.600 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-04-04T20:51:08.635Z,1743799868.635 [ElevatorOffsetCalculator] Loaded
2025-04-04T20:51:08.636Z,1743799868.636 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-04-04T20:51:08.636Z,1743799868.636 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-04-04T20:51:08.637Z,1743799868.637 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-04-04T20:51:09.300Z,1743799869.300 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-04-04T20:51:09.301Z,1743799869.301 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-04-04T20:51:09.474Z,1743799869.474 [BuoyancyServo] Loaded
2025-04-04T20:51:09.474Z,1743799869.474 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-04-04T20:51:09.498Z,1743799869.498 [ElevatorServo] Loaded
2025-04-04T20:51:09.499Z,1743799869.499 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-04-04T20:51:09.522Z,1743799869.522 [MassServo] Loaded
2025-04-04T20:51:09.522Z,1743799869.522 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-04-04T20:51:09.546Z,1743799869.546 [RudderServo] Loaded
2025-04-04T20:51:09.546Z,1743799869.546 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-04-04T20:51:09.565Z,1743799869.565 [ThrusterHE] Loaded
2025-04-04T20:51:09.565Z,1743799869.565 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-04-04T20:51:09.565Z,1743799869.565 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-04-04T20:51:09.566Z,1743799869.566 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-04-04T20:51:09.751Z,1743799869.751 [Aanderaa_O2] Loaded
2025-04-04T20:51:09.752Z,1743799869.752 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2025-04-04T20:51:09.816Z,1743799869.816 [CTD_Seabird] Loaded
2025-04-04T20:51:09.816Z,1743799869.816 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-04-04T20:51:09.817Z,1743799869.817 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0
2025-04-04T20:51:09.818Z,1743799869.818 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 941
2025-04-04T20:51:09.854Z,1743799869.854 [ESPComponent] Loaded
2025-04-04T20:51:09.854Z,1743799869.854 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-04-04T20:51:09.901Z,1743799869.901 [WetLabsBB2FL] Loaded
2025-04-04T20:51:09.901Z,1743799869.901 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-04-04T20:51:09.902Z,1743799869.902 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7E4E0
2025-04-04T20:51:09.903Z,1743799869.903 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 942
2025-04-04T20:51:09.903Z,1743799869.903 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-04-04T20:51:09.904Z,1743799869.904 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-04-04T20:51:10.078Z,1743799870.078 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-04-04T20:51:10.079Z,1743799870.079 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-04-04T20:51:10.307Z,1743799870.307 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-04-04T20:51:10.308Z,1743799870.308 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-04-04T20:51:10.733Z,1743799870.733 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-04-04T20:51:10.735Z,1743799870.735 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-04-04T20:51:10.808Z,1743799870.808 [VerticalControl](DEBUG): Construct VerticalControl.
2025-04-04T20:51:10.864Z,1743799870.864 [VerticalControl] Loaded
2025-04-04T20:51:10.864Z,1743799870.864 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-04-04T20:51:10.867Z,1743799870.867 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-04-04T20:51:10.915Z,1743799870.915 [HorizontalControl] Loaded
2025-04-04T20:51:10.915Z,1743799870.915 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-04-04T20:51:10.918Z,1743799870.918 [SpeedControl](DEBUG): Construct SpeedControl.
2025-04-04T20:51:10.919Z,1743799870.919 [SpeedControl] Loaded
2025-04-04T20:51:10.920Z,1743799870.920 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-04-04T20:51:10.923Z,1743799870.923 [LoopControl](DEBUG): Construct LoopControl.
2025-04-04T20:51:10.923Z,1743799870.923 [LoopControl] Loaded
2025-04-04T20:51:10.923Z,1743799870.923 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-04-04T20:51:10.924Z,1743799870.924 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-04-04T20:51:10.924Z,1743799870.924 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-04-04T20:51:11.131Z,1743799871.131 [SBIT](DEBUG): Construct Startup Built In Test.
2025-04-04T20:51:11.141Z,1743799871.141 [SBIT] Loaded
2025-04-04T20:51:11.141Z,1743799871.141 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-04-04T20:51:11.144Z,1743799871.144 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-04-04T20:51:11.158Z,1743799871.158 [IBIT] Loaded
2025-04-04T20:51:11.158Z,1743799871.158 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-04-04T20:51:11.163Z,1743799871.163 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-04-04T20:51:11.247Z,1743799871.247 [CBIT] Loaded
2025-04-04T20:51:11.247Z,1743799871.247 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-04-04T20:51:11.280Z,1743799871.280 [GFScanner] Loaded
2025-04-04T20:51:11.280Z,1743799871.280 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-04-04T20:51:11.281Z,1743799871.281 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-04-04T20:51:11.282Z,1743799871.282 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-04-04T20:51:11.331Z,1743799871.331 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-04-04T20:51:11.337Z,1743799871.337 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-04-04T20:51:11.340Z,1743799871.340 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-04-04T20:51:11.351Z,1743799871.351 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-04-04T20:51:11.352Z,1743799871.352 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-04-04T20:51:11.353Z,1743799871.353 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 943
2025-04-04T20:51:11.357Z,1743799871.357 [Supervisor](INFO): Main Thread ID is 832
2025-04-04T20:51:11.357Z,1743799871.357 [Supervisor](DEBUG): Running supervisor.
2025-04-04T20:51:11.358Z,1743799871.358 [CommandExec ThreadHandler](INFO): Handler Thread ID is 944
2025-04-04T20:51:11.358Z,1743799871.358 [CommandExec](INFO): Initializing the command executive.
2025-04-04T20:51:11.360Z,1743799871.360 [CommandLine ThreadHandler](INFO): Handler Thread ID is 945
2025-04-04T20:51:11.362Z,1743799871.362 [controlThread ThreadHandler](INFO): Handler Thread ID is 946
2025-04-04T20:51:11.363Z,1743799871.363 [controlThread](DEBUG): Initializing ControlThread
2025-04-04T20:51:11.364Z,1743799871.364 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-04-04T20:51:11.365Z,1743799871.365 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-04-04T20:51:11.368Z,1743799871.368 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-04-04T20:51:11.368Z,1743799871.368 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-04-04T20:51:11.368Z,1743799871.368 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-04-04T20:51:11.369Z,1743799871.369 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-04-04T20:51:11.369Z,1743799871.369 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-04-04T20:51:11.371Z,1743799871.371 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-04-04T20:51:11.373Z,1743799871.373 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-04-04T20:51:11.374Z,1743799871.374 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-04-04T20:51:11.374Z,1743799871.374 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-04-04T20:51:11.374Z,1743799871.374 [SBIT](INFO): Initialize SBIT Component.
2025-04-04T20:51:11.375Z,1743799871.375 [SBIT](IMPORTANT): git: 2025-03-31
2025-04-04T20:51:11.375Z,1743799871.375 [SBIT](INFO): git hash: fa5a0d22cc02557daa9f4ab77c7f518dfe223d1b
2025-04-04T20:51:11.375Z,1743799871.375 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-04-04T20:51:11.376Z,1743799871.376 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Mon Nov 27 01:04:18 PST 2023
2025-04-04T20:51:11.378Z,1743799871.378 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2025-04-04T20:51:11.378Z,1743799871.378 [IBIT](INFO): Initialize IBIT Component.
2025-04-04T20:51:11.379Z,1743799871.379 [CBIT](DEBUG): Initialize CBIT Component.
2025-04-04T20:51:11.380Z,1743799871.380 [logger ThreadHandler](INFO): Handler Thread ID is 947
2025-04-04T20:51:11.389Z,1743799871.389 [CBIT](DEBUG): Initialized mux pins.
2025-04-04T20:51:11.390Z,1743799871.390 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-04-04T20:51:11.390Z,1743799871.390 [CBIT](DEBUG): Initializing the watchdog timer.
2025-04-04T20:51:11.398Z,1743799871.398 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 948
2025-04-04T20:51:11.413Z,1743799871.413 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2025-04-04T20:51:11.414Z,1743799871.414 [CBIT](DEBUG): Initializing heartbeat.
2025-04-04T20:51:11.415Z,1743799871.415 [DAT ThreadHandler](INFO): Handler Thread ID is 949
2025-04-04T20:51:11.415Z,1743799871.415 [DAT](INFO): Powering up
2025-04-04T20:51:11.416Z,1743799871.416 [DAT](DEBUG): Initializing DAT.
2025-04-04T20:51:11.419Z,1743799871.419 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 951
2025-04-04T20:51:11.421Z,1743799871.421 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-04-04T20:51:11.442Z,1743799871.442 [Onboard ThreadHandler](INFO): Handler Thread ID is 952
2025-04-04T20:51:11.459Z,1743799871.459 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 953
2025-04-04T20:51:11.478Z,1743799871.478 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 954
2025-04-04T20:51:11.479Z,1743799871.479 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-04T20:51:11.483Z,1743799871.483 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 956
2025-04-04T20:51:11.486Z,1743799871.486 [CBIT](DEBUG): Deactivating emergency mode.
2025-04-04T20:51:11.490Z,1743799871.490 [WetLabsBB2FL](INFO): Powering up
2025-04-04T20:51:11.491Z,1743799871.491 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 958
2025-04-04T20:51:11.498Z,1743799871.498 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2025-04-04T20:51:11.502Z,1743799871.502 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-04-04T20:51:11.503Z,1743799871.503 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2025-04-04T20:51:11.503Z,1743799871.503 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-04-04T20:51:11.505Z,1743799871.505 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2025-04-04T20:51:11.505Z,1743799871.505 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-04-04T20:51:11.507Z,1743799871.507 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2025-04-04T20:51:11.507Z,1743799871.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-04-04T20:51:11.508Z,1743799871.508 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2025-04-04T20:51:11.526Z,1743799871.526 [CBIT](DEBUG): Backplane powered.
2025-04-04T20:51:11.526Z,1743799871.526 [GFScanner](DEBUG): Initializing GFScanner
2025-04-04T20:51:11.527Z,1743799871.527 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-04-04T20:51:11.527Z,1743799871.527 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-04-04T20:51:11.528Z,1743799871.528 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-04-04T20:51:11.532Z,1743799871.532 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-04-04T20:51:11.577Z,1743799871.577 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-04-04T20:51:11.602Z,1743799871.602 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA50M.000
2025-04-04T20:51:11.603Z,1743799871.603 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-04T20:51:11.652Z,1743799871.652 [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-04T20:51:11.653Z,1743799871.653 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-04-04T20:51:11.653Z,1743799871.653 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-04-04T20:51:11.657Z,1743799871.657 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-04-04T20:51:11.866Z,1743799871.866 [Radio_Surface](INFO): Powering up
2025-04-04T20:51:11.942Z,1743799871.942 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-04-04T20:51:11.948Z,1743799871.948 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-04-04T20:51:11.949Z,1743799871.949 [Default:A.Wait](DEBUG): Construct Wait.
2025-04-04T20:51:11.963Z,1743799871.963 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-04T20:51:11.997Z,1743799871.997 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-04-04T20:51:11.000Z,1743799872.000 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-04-04T20:51:12.006Z,1743799872.006 [Default:E.Execute](DEBUG): Construct Execute.
2025-04-04T20:51:12.009Z,1743799872.009 [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-04T20:51:12.046Z,1743799872.046 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DepthKeller33X,DropWeight,NAL9602,Power24vConverter,Aanderaa_O2,ESPComponent,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-04T20:51:12.058Z,1743799872.058 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-04T20:51:12.182Z,1743799872.182 [DepthKeller33X](INFO): Initializing.
2025-04-04T20:51:12.194Z,1743799872.194 [Power24vConverter](INFO): Powering up.
2025-04-04T20:51:12.203Z,1743799872.203 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-04T20:51:12.259Z,1743799872.259 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-04T20:51:12.266Z,1743799872.266 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-04T20:51:12.267Z,1743799872.267 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-04T20:51:12.274Z,1743799872.274 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-04T20:51:12.275Z,1743799872.275 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-04T20:51:12.278Z,1743799872.278 [MassServo](DEBUG): Initializing MassServo.
2025-04-04T20:51:12.279Z,1743799872.279 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-04T20:51:12.286Z,1743799872.286 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-04T20:51:12.287Z,1743799872.287 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-04T20:51:12.294Z,1743799872.294 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-04T20:51:12.296Z,1743799872.296 [CommandExec](FAULT): Scheduling is paused
2025-04-04T20:51:12.297Z,1743799872.297 [CBIT](INFO): Critical error at 20250404T205111
2025-04-04T20:51:12.297Z,1743799872.297 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-04-04T20:51:12.488Z,1743799872.488 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-04-04T20:51:12.488Z,1743799872.488 [DropWeight] Hardware Fault, FailCount= 1
2025-04-04T20:51:12.488Z,1743799872.488 [DropWeight](ERROR): Hardware Fault
2025-04-04T20:51:12.506Z,1743799872.506 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-04T20:51:12.520Z,1743799872.520 [CBIT](INFO): Critical error at 20250404T205112
2025-04-04T20:51:12.526Z,1743799872.526 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-04T20:51:12.527Z,1743799872.527 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-04T20:51:12.861Z,1743799872.861 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-04T20:51:12.869Z,1743799872.869 [CBIT](INFO): Critical error at 20250404T205112
2025-04-04T20:51:13.265Z,1743799873.265 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-04T20:51:13.594Z,1743799873.594 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2025-04-04T20:51:13.594Z,1743799873.594 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2025-04-04T20:51:13.594Z,1743799873.594 [BuoyancyServo] Communications Fault, FailCount= 1
2025-04-04T20:51:13.594Z,1743799873.594 [BuoyancyServo](ERROR): Communications Fault
2025-04-04T20:51:13.906Z,1743799873.906 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2025-04-04T20:51:13.906Z,1743799873.906 [ElevatorServo](FAULT): Elevator failed to initialize
2025-04-04T20:51:13.906Z,1743799873.906 [ElevatorServo] Communications Fault, FailCount= 1
2025-04-04T20:51:13.906Z,1743799873.906 [ElevatorServo](ERROR): Communications Fault
2025-04-04T20:51:14.226Z,1743799874.226 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2025-04-04T20:51:14.226Z,1743799874.226 [RudderServo](FAULT): Rudder failed to initialize
2025-04-04T20:51:14.226Z,1743799874.226