2022-12-05T23:44:09.058Z,1670283849.058 [Supervisor](DEBUG): Initializing supervisor.
2022-12-05T23:44:09.062Z,1670283849.062 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-12-05T23:44:09.063Z,1670283849.063 [SyncHandler](INFO): Protected caller Thread ID is 4312
2022-12-05T23:44:09.063Z,1670283849.063 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-12-05T23:44:09.064Z,1670283849.064 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-12-05T23:44:09.065Z,1670283849.065 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4313
2022-12-05T23:44:09.069Z,1670283849.069 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-12-05T23:44:09.087Z,1670283849.087 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-12-05T23:44:09.088Z,1670283849.088 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-12-05T23:44:09.088Z,1670283849.088 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4314
2022-12-05T23:44:09.092Z,1670283849.092 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-12-05T23:44:09.093Z,1670283849.093 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-12-05T23:44:09.094Z,1670283849.094 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4315
2022-12-05T23:44:09.096Z,1670283849.096 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-12-05T23:44:09.097Z,1670283849.097 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-12-05T23:44:09.097Z,1670283849.097 [logger ThreadHandler](INFO): Protected caller Thread ID is 4316
2022-12-05T23:44:09.101Z,1670283849.101 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-12-05T23:44:09.101Z,1670283849.101 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-12-05T23:44:09.108Z,1670283849.108 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-12-05T23:44:09.385Z,1670283849.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-12-05T23:44:09.387Z,1670283849.387 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-12-05T23:44:09.495Z,1670283849.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-12-05T23:44:09.496Z,1670283849.496 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-12-05T23:44:09.590Z,1670283849.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-12-05T23:44:09.591Z,1670283849.591 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-12-05T23:44:10.036Z,1670283850.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-12-05T23:44:10.036Z,1670283850.036 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-12-05T23:44:10.379Z,1670283850.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-12-05T23:44:10.380Z,1670283850.380 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-12-05T23:44:10.702Z,1670283850.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-12-05T23:44:10.703Z,1670283850.703 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-12-05T23:44:10.887Z,1670283850.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-12-05T23:44:10.888Z,1670283850.888 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-12-05T23:44:10.965Z,1670283850.965 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-12-05T23:44:11.046Z,1670283851.046 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-12-05T23:44:11.520Z,1670283851.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-12-05T23:44:11.521Z,1670283851.521 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-12-05T23:44:11.797Z,1670283851.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-12-05T23:44:11.798Z,1670283851.798 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-12-05T23:44:12.296Z,1670283852.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-12-05T23:44:12.296Z,1670283852.296 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-12-05T23:44:12.505Z,1670283852.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-12-05T23:44:12.506Z,1670283852.506 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-12-05T23:44:12.740Z,1670283852.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-12-05T23:44:12.741Z,1670283852.741 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-12-05T23:44:12.877Z,1670283852.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-12-05T23:44:12.877Z,1670283852.877 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-12-05T23:44:13.633Z,1670283853.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-12-05T23:44:13.636Z,1670283853.636 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-12-05T23:44:13.639Z,1670283853.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-12-05T23:44:13.722Z,1670283853.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-12-05T23:44:13.899Z,1670283853.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-12-05T23:44:14.262Z,1670283854.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-12-05T23:44:14.263Z,1670283854.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-12-05T23:44:15.070Z,1670283855.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-12-05T23:44:15.169Z,1670283855.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-12-05T23:44:15.260Z,1670283855.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-12-05T23:44:15.370Z,1670283855.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-12-05T23:44:15.463Z,1670283855.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-12-05T23:44:15.547Z,1670283855.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-12-05T23:44:15.644Z,1670283855.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-12-05T23:44:15.799Z,1670283855.799 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2022-12-05T23:44:15.799Z,1670283855.799 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-12-05T23:44:15.812Z,1670283855.812 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-12-05T23:44:15.883Z,1670283855.883 [DepthRateCalculator] Loaded
2022-12-05T23:44:15.883Z,1670283855.883 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-12-05T23:44:15.889Z,1670283855.889 [PitchRateCalculator] Loaded
2022-12-05T23:44:15.889Z,1670283855.889 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-12-05T23:44:15.899Z,1670283855.899 [SpeedCalculator] Loaded
2022-12-05T23:44:15.899Z,1670283855.899 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-12-05T23:44:15.904Z,1670283855.904 [YawRateCalculator] Loaded
2022-12-05T23:44:15.905Z,1670283855.905 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-12-05T23:44:15.926Z,1670283855.926 [ElevatorOffsetCalculator] Loaded
2022-12-05T23:44:15.926Z,1670283855.926 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-12-05T23:44:15.926Z,1670283855.926 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-12-05T23:44:15.927Z,1670283855.927 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-12-05T23:44:15.971Z,1670283855.971 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-12-05T23:44:15.972Z,1670283855.972 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-12-05T23:44:15.988Z,1670283855.988 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-12-05T23:44:15.988Z,1670283855.988 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-12-05T23:44:16.739Z,1670283856.739 [AHRS_M2] Loaded
2022-12-05T23:44:16.739Z,1670283856.739 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-12-05T23:44:16.991Z,1670283856.991 [BackseatComponent] Loaded
2022-12-05T23:44:16.991Z,1670283856.991 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-12-05T23:44:16.992Z,1670283856.992 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408384E0
2022-12-05T23:44:16.993Z,1670283856.993 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4399
2022-12-05T23:44:16.996Z,1670283856.996 [LcmUniversalReporter] Loaded
2022-12-05T23:44:16.996Z,1670283856.996 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-12-05T23:44:17.806Z,1670283857.806 [BPC1] Loaded
2022-12-05T23:44:17.807Z,1670283857.807 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-12-05T23:44:18.095Z,1670283858.095 [DataOverHttps] Loaded
2022-12-05T23:44:18.095Z,1670283858.095 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-12-05T23:44:18.096Z,1670283858.096 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408684E0
2022-12-05T23:44:18.097Z,1670283858.097 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4400
2022-12-05T23:44:18.118Z,1670283858.118 [Depth_Keller] Loaded
2022-12-05T23:44:18.118Z,1670283858.118 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-12-05T23:44:18.182Z,1670283858.182 [NAL9602] Loaded
2022-12-05T23:44:18.182Z,1670283858.182 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-12-05T23:44:18.212Z,1670283858.212 [Onboard] Loaded
2022-12-05T23:44:18.212Z,1670283858.212 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-12-05T23:44:18.213Z,1670283858.213 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408984E0
2022-12-05T23:44:18.213Z,1670283858.213 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4401
2022-12-05T23:44:18.219Z,1670283858.219 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2022-12-05T23:44:18.230Z,1670283858.230 [PowerOnly] Loaded
2022-12-05T23:44:18.230Z,1670283858.230 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-12-05T23:44:18.242Z,1670283858.242 [Power24vConverter] Loaded
2022-12-05T23:44:18.242Z,1670283858.242 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-12-05T23:44:18.257Z,1670283858.257 [Radio_Surface] Loaded
2022-12-05T23:44:18.257Z,1670283858.257 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-12-05T23:44:18.258Z,1670283858.258 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408C84E0
2022-12-05T23:44:18.258Z,1670283858.258 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4402
2022-12-05T23:44:18.259Z,1670283858.259 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-12-05T23:44:18.260Z,1670283858.260 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-12-05T23:44:18.400Z,1670283858.400 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-12-05T23:44:18.400Z,1670283858.400 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-12-05T23:44:18.442Z,1670283858.442 [DeadReckonUsingSpeedCalculator] Loaded
2022-12-05T23:44:18.442Z,1670283858.442 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-12-05T23:44:18.456Z,1670283858.456 [NavChart] Loaded
2022-12-05T23:44:18.456Z,1670283858.456 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-12-05T23:44:18.462Z,1670283858.462 [UniversalFixResidualReporter] Loaded
2022-12-05T23:44:18.462Z,1670283858.462 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-12-05T23:44:18.462Z,1670283858.462 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-12-05T23:44:18.463Z,1670283858.463 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-12-05T23:44:18.634Z,1670283858.634 [SBIT](DEBUG): Construct Startup Built In Test.
2022-12-05T23:44:18.648Z,1670283858.648 [SBIT] Loaded
2022-12-05T23:44:18.649Z,1670283858.649 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-12-05T23:44:18.652Z,1670283858.652 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-12-05T23:44:18.664Z,1670283858.664 [IBIT] Loaded
2022-12-05T23:44:18.665Z,1670283858.665 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-12-05T23:44:18.670Z,1670283858.670 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-12-05T23:44:18.773Z,1670283858.773 [CBIT] Loaded
2022-12-05T23:44:18.774Z,1670283858.774 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-12-05T23:44:18.774Z,1670283858.774 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-12-05T23:44:18.775Z,1670283858.775 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-12-05T23:44:18.954Z,1670283858.954 [PAR_Licor] Loaded
2022-12-05T23:44:18.954Z,1670283858.954 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-12-05T23:44:18.955Z,1670283858.955 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-12-05T23:44:18.956Z,1670283858.956 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-12-05T23:44:19.123Z,1670283859.123 [BuoyancyServo] Loaded
2022-12-05T23:44:19.123Z,1670283859.123 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-12-05T23:44:19.145Z,1670283859.145 [ElevatorServo] Loaded
2022-12-05T23:44:19.145Z,1670283859.145 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-12-05T23:44:19.166Z,1670283859.166 [MassServo] Loaded
2022-12-05T23:44:19.166Z,1670283859.166 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-12-05T23:44:19.188Z,1670283859.188 [RudderServo] Loaded
2022-12-05T23:44:19.188Z,1670283859.188 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-12-05T23:44:19.204Z,1670283859.204 [ThrusterHE] Loaded
2022-12-05T23:44:19.204Z,1670283859.204 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-12-05T23:44:19.204Z,1670283859.204 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-12-05T23:44:19.206Z,1670283859.206 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-12-05T23:44:19.274Z,1670283859.274 [VerticalControl](DEBUG): Construct VerticalControl.
2022-12-05T23:44:19.326Z,1670283859.326 [VerticalControl] Loaded
2022-12-05T23:44:19.326Z,1670283859.326 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-12-05T23:44:19.329Z,1670283859.329 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-12-05T23:44:19.366Z,1670283859.366 [HorizontalControl] Loaded
2022-12-05T23:44:19.366Z,1670283859.366 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-12-05T23:44:19.369Z,1670283859.369 [SpeedControl](DEBUG): Construct SpeedControl.
2022-12-05T23:44:19.370Z,1670283859.370 [SpeedControl] Loaded
2022-12-05T23:44:19.370Z,1670283859.370 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-12-05T23:44:19.373Z,1670283859.373 [LoopControl](DEBUG): Construct LoopControl.
2022-12-05T23:44:19.374Z,1670283859.374 [LoopControl] Loaded
2022-12-05T23:44:19.374Z,1670283859.374 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-12-05T23:44:19.374Z,1670283859.374 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-12-05T23:44:19.375Z,1670283859.375 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-12-05T23:44:19.848Z,1670283859.848 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-12-05T23:44:19.848Z,1670283859.848 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-12-05T23:44:20.083Z,1670283860.083 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-12-05T23:44:20.083Z,1670283860.083 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-12-05T23:44:20.448Z,1670283860.448 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-12-05T23:44:20.448Z,1670283860.448 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-12-05T23:44:20.530Z,1670283860.530 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-12-05T23:44:20.536Z,1670283860.536 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-12-05T23:44:20.539Z,1670283860.539 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-12-05T23:44:20.550Z,1670283860.550 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-12-05T23:44:20.551Z,1670283860.551 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0
2022-12-05T23:44:20.552Z,1670283860.552 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4403
2022-12-05T23:44:20.556Z,1670283860.556 [Supervisor](INFO): Main Thread ID is 4311
2022-12-05T23:44:20.556Z,1670283860.556 [Supervisor](DEBUG): Running supervisor.
2022-12-05T23:44:20.559Z,1670283860.559 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4404
2022-12-05T23:44:20.560Z,1670283860.560 [CommandExec](INFO): Initializing the command executive.
2022-12-05T23:44:20.561Z,1670283860.561 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4405
2022-12-05T23:44:20.563Z,1670283860.563 [controlThread ThreadHandler](INFO): Handler Thread ID is 4406
2022-12-05T23:44:20.564Z,1670283860.564 [controlThread](DEBUG): Initializing ControlThread
2022-12-05T23:44:20.565Z,1670283860.565 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-12-05T23:44:20.565Z,1670283860.565 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-12-05T23:44:20.565Z,1670283860.565 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-12-05T23:44:20.566Z,1670283860.566 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-12-05T23:44:20.566Z,1670283860.566 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-12-05T23:44:20.572Z,1670283860.572 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-12-05T23:44:20.573Z,1670283860.573 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-12-05T23:44:20.573Z,1670283860.573 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-12-05T23:44:20.574Z,1670283860.574 [SBIT](INFO): Initialize SBIT Component.
2022-12-05T23:44:20.574Z,1670283860.574 [SBIT](IMPORTANT): git: 2022-11-18
2022-12-05T23:44:20.574Z,1670283860.574 [SBIT](INFO): git hash: 1213f736678a71319c30bd1b0c503177f42d9940
2022-12-05T23:44:20.575Z,1670283860.575 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-12-05T23:44:20.576Z,1670283860.576 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-12-05T23:44:20.577Z,1670283860.577 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2022-12-05T23:44:20.578Z,1670283860.578 [IBIT](INFO): Initialize IBIT Component.
2022-12-05T23:44:20.578Z,1670283860.578 [CBIT](DEBUG): Initialize CBIT Component.
2022-12-05T23:44:20.579Z,1670283860.579 [logger ThreadHandler](INFO): Handler Thread ID is 4407
2022-12-05T23:44:20.591Z,1670283860.591 [CBIT](DEBUG): Initialized mux pins.
2022-12-05T23:44:20.591Z,1670283860.591 [CBIT](DEBUG): Initializing the watchdog timer.
2022-12-05T23:44:20.599Z,1670283860.599 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4408
2022-12-05T23:44:20.611Z,1670283860.611 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4409
2022-12-05T23:44:20.612Z,1670283860.612 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-12-05T23:44:20.615Z,1670283860.615 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-12-05T23:44:20.616Z,1670283860.616 [CBIT](DEBUG): Initializing heartbeat.
2022-12-05T23:44:20.623Z,1670283860.623 [Onboard ThreadHandler](INFO): Handler Thread ID is 4410
2022-12-05T23:44:20.640Z,1670283860.640 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4411
2022-12-05T23:44:20.664Z,1670283860.664 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4412
2022-12-05T23:44:20.670Z,1670283860.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-12-05T23:44:20.670Z,1670283860.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-12-05T23:44:20.670Z,1670283860.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-12-05T23:44:20.670Z,1670283860.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-12-05T23:44:20.670Z,1670283860.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-12-05T23:44:20.670Z,1670283860.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-12-05T23:44:20.671Z,1670283860.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-12-05T23:44:20.671Z,1670283860.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-12-05T23:44:20.687Z,1670283860.687 [CBIT](DEBUG): Deactivating GF circuits.
2022-12-05T23:44:20.687Z,1670283860.687 [CBIT](DEBUG): Deactivating emergency mode.
2022-12-05T23:44:20.723Z,1670283860.723 [CBIT](DEBUG): Backplane powered.
2022-12-05T23:44:20.728Z,1670283860.728 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-12-05T23:44:20.730Z,1670283860.730 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-12-05T23:44:20.731Z,1670283860.731 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-12-05T23:44:20.731Z,1670283860.731 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-12-05T23:44:20.732Z,1670283860.732 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-12-05T23:44:20.759Z,1670283860.759 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-05T23:44:20.793Z,1670283860.793 [MissionManager](DEBUG):
2022-12-05T23:44:20.794Z,1670283860.794 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-12-05T23:44:20.851Z,1670283860.851 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-12-05T23:44:20.853Z,1670283860.853 [Default:A.Wait](DEBUG): Construct Wait.
2022-12-05T23:44:20.855Z,1670283860.855 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-05T23:44:20.882Z,1670283860.882 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-12-05T23:44:20.884Z,1670283860.884 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-12-05T23:44:20.917Z,1670283860.917 [Default:E.Execute](DEBUG): Construct Execute.
2022-12-05T23:44:20.920Z,1670283860.920 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2022-12-05T23:44:20.935Z,1670283860.935 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T23:44:20.941Z,1670283860.941 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-12-05T23:44:21.026Z,1670283861.026 [Power24vConverter](INFO): Powering up.
2022-12-05T23:44:21.047Z,1670283861.047 [Radio_Surface](INFO): Powering up
2022-12-05T23:44:21.078Z,1670283861.078 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-12-05T23:44:21.083Z,1670283861.083 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-12-05T23:44:21.085Z,1670283861.085 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-12-05T23:44:21.091Z,1670283861.091 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-12-05T23:44:21.092Z,1670283861.092 [MassServo](DEBUG): Initializing EZServoServo.
2022-12-05T23:44:21.099Z,1670283861.099 [MassServo](DEBUG): Initializing MassServo.
2022-12-05T23:44:21.100Z,1670283861.100 [RudderServo](DEBUG): Initializing EZServoServo.
2022-12-05T23:44:21.107Z,1670283861.107 [RudderServo](DEBUG): Initializing RudderServo.
2022-12-05T23:44:21.108Z,1670283861.108 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-12-05T23:44:21.119Z,1670283861.119 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-12-05T23:44:22.111Z,1670283862.111 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-12-05T23:44:22.111Z,1670283862.111 [RudderServo](FAULT): Rudder failed to initialize
2022-12-05T23:44:22.111Z,1670283862.111 [RudderServo] Communications Fault, FailCount= 1
2022-12-05T23:44:22.111Z,1670283862.111 [RudderServo](ERROR): Communications Fault
2022-12-05T23:44:22.115Z,1670283862.115 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-12-05T23:44:22.306Z,1670283862.306 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-12-05T23:44:22.306Z,1670283862.306 [RudderServo](INFO): Powering down
2022-12-05T23:44:22.983Z,1670283862.983 [RudderServo](DEBUG): Initializing EZServoServo.
2022-12-05T23:44:23.104Z,1670283863.104 [RudderServo](DEBUG): Initializing RudderServo.
2022-12-05T23:44:23.108Z,1670283863.108 [CBIT](INFO): Clearing failed state for component RudderServo
2022-12-05T23:44:23.108Z,1670283863.108 [RudderServo] No Fault, FailCount= 1
2022-12-05T23:44:26.872Z,1670283866.872 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-12-05T23:44:30.985Z,1670283870.985 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004669
2022-12-05T23:44:41.197Z,1670283881.197 [SBIT](IMPORTANT): Beginning Startup BIT
2022-12-05T23:44:41.202Z,1670283881.202 [CBIT](IMPORTANT): Beginning ground fault scan
2022-12-05T23:44:48.424Z,1670283888.424 [NAL9602](INFO): Powering up NAL9602
2022-12-05T23:44:52.119Z,1670283892.119 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003815
CHAN A1 (24V): 0.000878
CHAN A2 (12V): -0.006161
CHAN A3 (5V): -0.002348
CHAN B0 (3.3V): -0.001378
CHAN B1 (3.15aV): -0.000888
CHAN B2 (3.15bV): -0.001579
CHAN B3 (GND): -0.000575
OPEN: 0.004350
Full Scale: +/- 1 mA
2022-12-05T23:44:59.360Z,1670283899.360 [NAL9602](INFO): NAL9602 initialized
2022-12-05T23:45:28.336Z,1670283928.336 [CommandExec](IMPORTANT): got command quit
2022-12-05T23:45:29.339Z,1670283929.339 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-12-05T23:45:29.339Z,1670283929.339 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:29.339Z,1670283929.339 [CommandExec](INFO): Uninitializing the command executive.
2022-12-05T23:45:29.339Z,1670283929.339 [CommandExec](INFO): Uninitializing the command scheduler.
2022-12-05T23:45:29.340Z,1670283929.340 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:29.474Z,1670283929.474 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2022-12-05T23:45:29.474Z,1670283929.474 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-12-05T23:45:29.475Z,1670283929.475 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:29.475Z,1670283929.475 [NavChartDb](INFO): Join timeout helper Thread ID is 4438
2022-12-05T23:45:29.859Z,1670283929.859 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:29.859Z,1670283929.859 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:29.875Z,1670283929.875 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-12-05T23:45:29.875Z,1670283929.875 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:29.875Z,1670283929.875 [Radio_Surface](INFO): Join timeout helper Thread ID is 4439
2022-12-05T23:45:30.143Z,1670283930.143 [Radio_Surface](INFO): Powering down
2022-12-05T23:45:30.144Z,1670283930.144 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:30.144Z,1670283930.144 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.163Z,1670283930.163 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-12-05T23:45:30.163Z,1670283930.163 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.164Z,1670283930.164 [Onboard](INFO): Join timeout helper Thread ID is 4440
2022-12-05T23:45:30.683Z,1670283930.683 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:30.683Z,1670283930.683 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.687Z,1670283930.687 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-12-05T23:45:30.687Z,1670283930.687 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.687Z,1670283930.687 [DataOverHttps](INFO): Join timeout helper Thread ID is 4441
2022-12-05T23:45:30.719Z,1670283930.719 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:30.719Z,1670283930.719 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.728Z,1670283930.728 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-12-05T23:45:30.728Z,1670283930.728 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.732Z,1670283930.732 [BackseatComponent](INFO): Join timeout helper Thread ID is 4442
2022-12-05T23:45:30.883Z,1670283930.883 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:30.883Z,1670283930.883 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.896Z,1670283930.896 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-12-05T23:45:30.896Z,1670283930.896 [logger ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.896Z,1670283930.896 [logger](INFO): Join timeout helper Thread ID is 4443
2022-12-05T23:45:30.933Z,1670283930.933 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:30.934Z,1670283930.934 [logger ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.936Z,1670283930.936 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-12-05T23:45:30.936Z,1670283930.936 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:30.936Z,1670283930.936 [CommandLine](INFO): Join timeout helper Thread ID is 4444
2022-12-05T23:45:30.999Z,1670283930.999 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:30.999Z,1670283930.999 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.007Z,1670283931.007 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-12-05T23:45:31.008Z,1670283931.008 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.008Z,1670283931.008 [CommandExec](INFO): Join timeout helper Thread ID is 4445
2022-12-05T23:45:31.009Z,1670283931.009 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-12-05T23:45:31.009Z,1670283931.009 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.010Z,1670283931.010 [controlThread](INFO): Join timeout helper Thread ID is 4446
2022-12-05T23:45:31.255Z,1670283931.255 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T23:45:31.255Z,1670283931.255 [controlThread](DEBUG): Uninitializing ControlThread
2022-12-05T23:45:31.256Z,1670283931.256 [AHRS_M2](INFO): Powering down
2022-12-05T23:45:31.328Z,1670283931.328 [NAL9602](INFO): Powering down
2022-12-05T23:45:31.330Z,1670283931.330 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-12-05T23:45:31.331Z,1670283931.331 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-12-05T23:45:31.331Z,1670283931.331 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-12-05T23:45:31.332Z,1670283931.332 [MissionManager](INFO): Uninitializing Mission Default
2022-12-05T23:45:31.334Z,1670283931.334 [MissionManager](INFO): Uninitializing Mission Startup
2022-12-05T23:45:31.336Z,1670283931.336 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-12-05T23:45:31.336Z,1670283931.336 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-12-05T23:45:31.337Z,1670283931.337 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-12-05T23:45:31.337Z,1670283931.337 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-12-05T23:45:31.337Z,1670283931.337 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-12-05T23:45:31.337Z,1670283931.337 [BuoyancyServo](INFO): Powering down
2022-12-05T23:45:31.351Z,1670283931.351 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-12-05T23:45:31.351Z,1670283931.351 [ElevatorServo](INFO): Powering down
2022-12-05T23:45:31.352Z,1670283931.352 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-12-05T23:45:31.352Z,1670283931.352 [MassServo](INFO): Powering down
2022-12-05T23:45:31.353Z,1670283931.353 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-12-05T23:45:31.353Z,1670283931.353 [RudderServo](INFO): Powering down
2022-12-05T23:45:31.354Z,1670283931.354 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-12-05T23:45:31.354Z,1670283931.354 [ThrusterHE](INFO): Powering down
2022-12-05T23:45:31.355Z,1670283931.355 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-12-05T23:45:31.355Z,1670283931.355 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-12-05T23:45:31.355Z,1670283931.355 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-12-05T23:45:31.355Z,1670283931.355 [CBIT](DEBUG): Powering off loads.
2022-12-05T23:45:31.367Z,1670283931.367 [CBIT](DEBUG): Disabling WDT.
2022-12-05T23:45:31.379Z,1670283931.379 [CBIT](DEBUG): Opening all GF detection circuits.
2022-12-05T23:45:31.380Z,1670283931.380 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.400Z,1670283931.400 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.403Z,1670283931.403 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.409Z,1670283931.409 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.451Z,1670283931.451 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.546Z,1670283931.546 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-12-05T23:45:31.590Z,1670283931.590 [logger ThreadHandler](INFO): Thread cancelled.