2023-01-25T22:49:03.061Z,1674686943.061 [Supervisor](DEBUG): Initializing supervisor.
2023-01-25T22:49:03.065Z,1674686943.065 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-01-25T22:49:03.065Z,1674686943.065 [SyncHandler](INFO): Protected caller Thread ID is 6261
2023-01-25T22:49:03.066Z,1674686943.066 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-01-25T22:49:03.067Z,1674686943.067 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-01-25T22:49:03.067Z,1674686943.067 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6262
2023-01-25T22:49:03.071Z,1674686943.071 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-01-25T22:49:03.088Z,1674686943.088 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-01-25T22:49:03.089Z,1674686943.089 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-01-25T22:49:03.089Z,1674686943.089 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6263
2023-01-25T22:49:03.093Z,1674686943.093 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-01-25T22:49:03.094Z,1674686943.094 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-01-25T22:49:03.095Z,1674686943.095 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6264
2023-01-25T22:49:03.097Z,1674686943.097 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-01-25T22:49:03.098Z,1674686943.098 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-01-25T22:49:03.098Z,1674686943.098 [logger ThreadHandler](INFO): Protected caller Thread ID is 6265
2023-01-25T22:49:03.102Z,1674686943.102 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-01-25T22:49:03.102Z,1674686943.102 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-01-25T22:49:03.104Z,1674686943.104 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-01-25T22:49:03.309Z,1674686943.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-01-25T22:49:03.310Z,1674686943.310 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-01-25T22:49:03.415Z,1674686943.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-01-25T22:49:03.416Z,1674686943.416 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-01-25T22:49:03.510Z,1674686943.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-01-25T22:49:03.511Z,1674686943.511 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-01-25T22:49:03.961Z,1674686943.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-01-25T22:49:03.962Z,1674686943.962 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-01-25T22:49:04.294Z,1674686944.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-01-25T22:49:04.294Z,1674686944.294 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-01-25T22:49:04.613Z,1674686944.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-01-25T22:49:04.614Z,1674686944.614 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-01-25T22:49:04.792Z,1674686944.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-01-25T22:49:04.792Z,1674686944.792 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-01-25T22:49:04.876Z,1674686944.876 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-01-25T22:49:04.957Z,1674686944.957 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-01-25T22:49:05.413Z,1674686945.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-01-25T22:49:05.414Z,1674686945.414 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-01-25T22:49:05.687Z,1674686945.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-01-25T22:49:05.688Z,1674686945.688 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-01-25T22:49:06.169Z,1674686946.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-01-25T22:49:06.169Z,1674686946.169 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-01-25T22:49:06.375Z,1674686946.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-01-25T22:49:06.376Z,1674686946.376 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-01-25T22:49:06.609Z,1674686946.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-01-25T22:49:06.609Z,1674686946.609 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-01-25T22:49:06.745Z,1674686946.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-01-25T22:49:06.745Z,1674686946.745 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-01-25T22:49:07.479Z,1674686947.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-01-25T22:49:07.481Z,1674686947.481 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2023-01-25T22:49:07.482Z,1674686947.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2023-01-25T22:49:07.567Z,1674686947.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2023-01-25T22:49:07.741Z,1674686947.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2023-01-25T22:49:08.646Z,1674686948.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-01-25T22:49:08.646Z,1674686948.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2023-01-25T22:49:08.767Z,1674686948.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2023-01-25T22:49:08.867Z,1674686948.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2023-01-25T22:49:08.960Z,1674686948.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2023-01-25T22:49:09.071Z,1674686949.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2023-01-25T22:49:09.165Z,1674686949.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2023-01-25T22:49:09.252Z,1674686949.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2023-01-25T22:49:09.351Z,1674686949.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2023-01-25T22:49:09.506Z,1674686949.506 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2023-01-25T22:49:09.506Z,1674686949.506 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-01-25T22:49:09.508Z,1674686949.508 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-01-25T22:49:09.557Z,1674686949.557 [DepthRateCalculator] Loaded
2023-01-25T22:49:09.557Z,1674686949.557 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-01-25T22:49:09.562Z,1674686949.562 [PitchRateCalculator] Loaded
2023-01-25T22:49:09.563Z,1674686949.563 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-01-25T22:49:09.573Z,1674686949.573 [SpeedCalculator] Loaded
2023-01-25T22:49:09.573Z,1674686949.573 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-01-25T22:49:09.578Z,1674686949.578 [YawRateCalculator] Loaded
2023-01-25T22:49:09.578Z,1674686949.578 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-01-25T22:49:09.598Z,1674686949.598 [ElevatorOffsetCalculator] Loaded
2023-01-25T22:49:09.599Z,1674686949.599 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-01-25T22:49:09.599Z,1674686949.599 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-01-25T22:49:09.600Z,1674686949.600 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-01-25T22:49:09.624Z,1674686949.624 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-01-25T22:49:09.625Z,1674686949.625 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-01-25T22:49:09.634Z,1674686949.634 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-01-25T22:49:09.635Z,1674686949.635 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-01-25T22:49:10.009Z,1674686950.009 [AHRS_M2] Loaded
2023-01-25T22:49:10.010Z,1674686950.010 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-01-25T22:49:10.044Z,1674686950.044 [BackseatComponent] Loaded
2023-01-25T22:49:10.044Z,1674686950.044 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-01-25T22:49:10.045Z,1674686950.045 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4083D4E0
2023-01-25T22:49:10.045Z,1674686950.045 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6348
2023-01-25T22:49:10.048Z,1674686950.048 [LcmUniversalReporter] Loaded
2023-01-25T22:49:10.048Z,1674686950.048 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-01-25T22:49:10.825Z,1674686950.825 [BPC1] Loaded
2023-01-25T22:49:10.826Z,1674686950.826 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-01-25T22:49:10.903Z,1674686950.903 [DataOverHttps] Loaded
2023-01-25T22:49:10.903Z,1674686950.903 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-01-25T22:49:10.904Z,1674686950.904 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4086D4E0
2023-01-25T22:49:10.904Z,1674686950.904 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6349
2023-01-25T22:49:10.924Z,1674686950.924 [Depth_Keller] Loaded
2023-01-25T22:49:10.924Z,1674686950.924 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-01-25T22:49:10.929Z,1674686950.929 [DropWeight] Loaded
2023-01-25T22:49:10.929Z,1674686950.929 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-01-25T22:49:10.990Z,1674686950.990 [NAL9602] Loaded
2023-01-25T22:49:10.990Z,1674686950.990 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-01-25T22:49:11.018Z,1674686951.018 [Onboard] Loaded
2023-01-25T22:49:11.019Z,1674686951.019 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-01-25T22:49:11.020Z,1674686951.020 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4089D4E0
2023-01-25T22:49:11.020Z,1674686951.020 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6350
2023-01-25T22:49:11.026Z,1674686951.026 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2023-01-25T22:49:11.036Z,1674686951.036 [PowerOnly] Loaded
2023-01-25T22:49:11.037Z,1674686951.037 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-01-25T22:49:11.048Z,1674686951.048 [Power24vConverter] Loaded
2023-01-25T22:49:11.048Z,1674686951.048 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-01-25T22:49:11.062Z,1674686951.062 [Radio_Surface] Loaded
2023-01-25T22:49:11.062Z,1674686951.062 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-01-25T22:49:11.063Z,1674686951.063 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408CD4E0
2023-01-25T22:49:11.063Z,1674686951.063 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6351
2023-01-25T22:49:11.152Z,1674686951.152 [DAT] Loaded
2023-01-25T22:49:11.152Z,1674686951.152 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-01-25T22:49:11.153Z,1674686951.153 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-01-25T22:49:11.153Z,1674686951.153 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6352
2023-01-25T22:49:11.174Z,1674686951.174 [Waterlinked] Loaded
2023-01-25T22:49:11.174Z,1674686951.174 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2023-01-25T22:49:11.175Z,1674686951.175 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-01-25T22:49:11.175Z,1674686951.175 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-01-25T22:49:11.280Z,1674686951.280 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-01-25T22:49:11.280Z,1674686951.280 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-01-25T22:49:11.321Z,1674686951.321 [DeadReckonUsingSpeedCalculator] Loaded
2023-01-25T22:49:11.321Z,1674686951.321 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-01-25T22:49:11.334Z,1674686951.334 [NavChart] Loaded
2023-01-25T22:49:11.334Z,1674686951.334 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-01-25T22:49:11.340Z,1674686951.340 [UniversalFixResidualReporter] Loaded
2023-01-25T22:49:11.340Z,1674686951.340 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-01-25T22:49:11.341Z,1674686951.341 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-01-25T22:49:11.341Z,1674686951.341 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-01-25T22:49:11.469Z,1674686951.469 [SBIT](DEBUG): Construct Startup Built In Test.
2023-01-25T22:49:11.483Z,1674686951.483 [SBIT] Loaded
2023-01-25T22:49:11.483Z,1674686951.483 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-01-25T22:49:11.486Z,1674686951.486 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-01-25T22:49:11.498Z,1674686951.498 [IBIT] Loaded
2023-01-25T22:49:11.499Z,1674686951.499 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-01-25T22:49:11.504Z,1674686951.504 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-01-25T22:49:11.605Z,1674686951.605 [CBIT] Loaded
2023-01-25T22:49:11.605Z,1674686951.605 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-01-25T22:49:11.606Z,1674686951.606 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-01-25T22:49:11.606Z,1674686951.606 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-01-25T22:49:11.766Z,1674686951.766 [CTD_Seabird] Loaded
2023-01-25T22:49:11.766Z,1674686951.766 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-01-25T22:49:11.768Z,1674686951.768 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A014E0
2023-01-25T22:49:11.768Z,1674686951.768 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6353
2023-01-25T22:49:11.787Z,1674686951.787 [PAR_Licor] Loaded
2023-01-25T22:49:11.788Z,1674686951.788 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-01-25T22:49:11.818Z,1674686951.818 [WetLabsBB2FL] Loaded
2023-01-25T22:49:11.819Z,1674686951.819 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-01-25T22:49:11.820Z,1674686951.820 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0
2023-01-25T22:49:11.820Z,1674686951.820 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6354
2023-01-25T22:49:11.821Z,1674686951.821 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-01-25T22:49:11.821Z,1674686951.821 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-01-25T22:49:11.946Z,1674686951.946 [BuoyancyServo] Loaded
2023-01-25T22:49:11.946Z,1674686951.946 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-01-25T22:49:11.968Z,1674686951.968 [ElevatorServo] Loaded
2023-01-25T22:49:11.968Z,1674686951.968 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-01-25T22:49:11.989Z,1674686951.989 [MassServo] Loaded
2023-01-25T22:49:11.989Z,1674686951.989 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-01-25T22:49:12.009Z,1674686952.009 [RudderServo] Loaded
2023-01-25T22:49:12.009Z,1674686952.009 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-01-25T22:49:12.025Z,1674686952.025 [ThrusterHE] Loaded
2023-01-25T22:49:12.025Z,1674686952.025 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-01-25T22:49:12.025Z,1674686952.025 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-01-25T22:49:12.026Z,1674686952.026 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-01-25T22:49:12.074Z,1674686952.074 [VerticalControl](DEBUG): Construct VerticalControl.
2023-01-25T22:49:12.124Z,1674686952.124 [VerticalControl] Loaded
2023-01-25T22:49:12.124Z,1674686952.124 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-01-25T22:49:12.127Z,1674686952.127 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-01-25T22:49:12.163Z,1674686952.163 [HorizontalControl] Loaded
2023-01-25T22:49:12.163Z,1674686952.163 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-01-25T22:49:12.166Z,1674686952.166 [SpeedControl](DEBUG): Construct SpeedControl.
2023-01-25T22:49:12.168Z,1674686952.168 [SpeedControl] Loaded
2023-01-25T22:49:12.168Z,1674686952.168 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-01-25T22:49:12.171Z,1674686952.171 [LoopControl](DEBUG): Construct LoopControl.
2023-01-25T22:49:12.171Z,1674686952.171 [LoopControl] Loaded
2023-01-25T22:49:12.171Z,1674686952.171 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-01-25T22:49:12.172Z,1674686952.172 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-01-25T22:49:12.172Z,1674686952.172 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-01-25T22:49:12.266Z,1674686952.266 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-01-25T22:49:12.267Z,1674686952.267 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-01-25T22:49:12.405Z,1674686952.405 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-01-25T22:49:12.406Z,1674686952.406 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-01-25T22:49:12.696Z,1674686952.696 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-01-25T22:49:12.696Z,1674686952.696 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-01-25T22:49:12.749Z,1674686952.749 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-01-25T22:49:12.755Z,1674686952.755 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-01-25T22:49:12.758Z,1674686952.758 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-01-25T22:49:12.769Z,1674686952.769 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-01-25T22:49:12.770Z,1674686952.770 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD44E0
2023-01-25T22:49:12.770Z,1674686952.770 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6355
2023-01-25T22:49:12.774Z,1674686952.774 [Supervisor](INFO): Main Thread ID is 6260
2023-01-25T22:49:12.775Z,1674686952.775 [Supervisor](DEBUG): Running supervisor.
2023-01-25T22:49:12.775Z,1674686952.775 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6356
2023-01-25T22:49:12.776Z,1674686952.776 [CommandExec](INFO): Initializing the command executive.
2023-01-25T22:49:12.777Z,1674686952.777 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6357
2023-01-25T22:49:12.781Z,1674686952.781 [controlThread ThreadHandler](INFO): Handler Thread ID is 6358
2023-01-25T22:49:12.781Z,1674686952.781 [controlThread](DEBUG): Initializing ControlThread
2023-01-25T22:49:12.782Z,1674686952.782 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-01-25T22:49:12.782Z,1674686952.782 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-01-25T22:49:12.783Z,1674686952.783 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-01-25T22:49:12.783Z,1674686952.783 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-01-25T22:49:12.783Z,1674686952.783 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-01-25T22:49:12.790Z,1674686952.790 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-01-25T22:49:12.790Z,1674686952.790 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-01-25T22:49:12.791Z,1674686952.791 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-01-25T22:49:12.791Z,1674686952.791 [SBIT](INFO): Initialize SBIT Component.
2023-01-25T22:49:12.792Z,1674686952.792 [SBIT](IMPORTANT): git: 2022-12-14-5-g868688940
2023-01-25T22:49:12.792Z,1674686952.792 [SBIT](INFO): git hash: 868688940ec3a995afef730fa44917767e114440
2023-01-25T22:49:12.792Z,1674686952.792 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-01-25T22:49:12.793Z,1674686952.793 [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
2023-01-25T22:49:12.794Z,1674686952.794 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2023-01-25T22:49:12.795Z,1674686952.795 [IBIT](INFO): Initialize IBIT Component.
2023-01-25T22:49:12.796Z,1674686952.796 [CBIT](DEBUG): Initialize CBIT Component.
2023-01-25T22:49:12.797Z,1674686952.797 [logger ThreadHandler](INFO): Handler Thread ID is 6359
2023-01-25T22:49:12.807Z,1674686952.807 [CBIT](DEBUG): Initialized mux pins.
2023-01-25T22:49:12.807Z,1674686952.807 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2023-01-25T22:49:12.807Z,1674686952.807 [CBIT](DEBUG): Initializing the watchdog timer.
2023-01-25T22:49:12.816Z,1674686952.816 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6360
2023-01-25T22:49:12.828Z,1674686952.828 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6361
2023-01-25T22:49:12.829Z,1674686952.829 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-01-25T22:49:12.831Z,1674686952.831 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2023-01-25T22:49:12.831Z,1674686952.831 [CBIT] Hardware Fault, FailCount= 1
2023-01-25T22:49:12.831Z,1674686952.831 [CBIT](ERROR): Hardware Fault
2023-01-25T22:49:12.832Z,1674686952.832 [CBIT](DEBUG): Initializing heartbeat.
2023-01-25T22:49:12.840Z,1674686952.840 [Onboard ThreadHandler](INFO): Handler Thread ID is 6362
2023-01-25T22:49:12.857Z,1674686952.857 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6363
2023-01-25T22:49:12.880Z,1674686952.880 [DAT ThreadHandler](INFO): Handler Thread ID is 6364
2023-01-25T22:49:12.881Z,1674686952.881 [DAT](INFO): Powering up
2023-01-25T22:49:12.881Z,1674686952.881 [DAT](DEBUG): Initializing DAT.
2023-01-25T22:49:12.885Z,1674686952.885 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6366
2023-01-25T22:49:12.886Z,1674686952.886 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-01-25T22:49:12.888Z,1674686952.888 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6368
2023-01-25T22:49:12.892Z,1674686952.892 [WetLabsBB2FL](INFO): Powering up
2023-01-25T22:49:12.893Z,1674686952.893 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6370
2023-01-25T22:49:12.896Z,1674686952.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-01-25T22:49:12.896Z,1674686952.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-01-25T22:49:12.896Z,1674686952.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-01-25T22:49:12.896Z,1674686952.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-01-25T22:49:12.896Z,1674686952.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-01-25T22:49:12.897Z,1674686952.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-01-25T22:49:12.897Z,1674686952.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-01-25T22:49:12.897Z,1674686952.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-01-25T22:49:12.903Z,1674686952.903 [CBIT](DEBUG): Deactivating GF circuits.
2023-01-25T22:49:12.903Z,1674686952.903 [CBIT](DEBUG): Deactivating emergency mode.
2023-01-25T22:49:12.939Z,1674686952.939 [CBIT](DEBUG): Backplane powered.
2023-01-25T22:49:12.945Z,1674686952.945 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-01-25T22:49:12.946Z,1674686952.946 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-01-25T22:49:12.947Z,1674686952.947 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-01-25T22:49:12.948Z,1674686952.948 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-01-25T22:49:12.949Z,1674686952.949 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-01-25T22:49:12.963Z,1674686952.963 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-25T22:49:12.986Z,1674686952.986 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       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>


2023-01-25T22:49:12.991Z,1674686952.991 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-01-25T22:49:13.053Z,1674686953.053 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-01-25T22:49:13.055Z,1674686953.055 [Default:A.Wait](DEBUG): Construct Wait.
2023-01-25T22:49:13.057Z,1674686953.057 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-25T22:49:13.089Z,1674686953.089 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-01-25T22:49:13.091Z,1674686953.091 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-01-25T22:49:13.097Z,1674686953.097 [Default:E.Execute](DEBUG): Construct Execute.
2023-01-25T22:49:13.120Z,1674686953.120 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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>


2023-01-25T22:49:13.125Z,1674686953.125 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Waterlinked,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,
2023-01-25T22:49:13.152Z,1674686953.152 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-01-25T22:49:13.222Z,1674686953.222 [Power24vConverter](INFO): Powering up.
2023-01-25T22:49:13.223Z,1674686953.223 [Waterlinked](INFO): Start
2023-01-25T22:49:13.223Z,1674686953.223 [Waterlinked](IMPORTANT): Expecting PD6 message format
2023-01-25T22:49:13.258Z,1674686953.258 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:13.263Z,1674686953.263 [Radio_Surface](INFO): Powering up
2023-01-25T22:49:13.264Z,1674686953.264 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-01-25T22:49:13.265Z,1674686953.265 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:13.272Z,1674686953.272 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-01-25T22:49:13.272Z,1674686953.272 [MassServo](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:13.280Z,1674686953.280 [MassServo](DEBUG): Initializing MassServo.
2023-01-25T22:49:13.280Z,1674686953.280 [RudderServo](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:13.288Z,1674686953.288 [RudderServo](DEBUG): Initializing RudderServo.
2023-01-25T22:49:13.288Z,1674686953.288 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:13.296Z,1674686953.296 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-01-25T22:49:13.297Z,1674686953.297 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-01-25T22:49:13.297Z,1674686953.297 [CBIT](DEBUG): Powering off loads.
2023-01-25T22:49:13.311Z,1674686953.311 [CBIT](DEBUG): Disabling WDT.
2023-01-25T22:49:13.323Z,1674686953.323 [CBIT](DEBUG): Opening all GF detection circuits.
2023-01-25T22:49:13.564Z,1674686953.564 [Waterlinked](INFO): Starting
2023-01-25T22:49:13.941Z,1674686953.941 [Waterlinked](INFO): Starting
2023-01-25T22:49:14.259Z,1674686954.259 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-01-25T22:49:14.259Z,1674686954.259 [RudderServo](FAULT): Rudder failed to initialize
2023-01-25T22:49:14.259Z,1674686954.259 [RudderServo] Communications Fault, FailCount= 1
2023-01-25T22:49:14.260Z,1674686954.260 [RudderServo](ERROR): Communications Fault
2023-01-25T22:49:14.346Z,1674686954.346 [Waterlinked](INFO): Starting
2023-01-25T22:49:14.683Z,1674686954.683 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2023-01-25T22:49:14.683Z,1674686954.683 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2023-01-25T22:49:14.683Z,1674686954.683 [BuoyancyServo] Communications Fault, FailCount= 1
2023-01-25T22:49:14.683Z,1674686954.683 [BuoyancyServo](ERROR): Communications Fault
2023-01-25T22:49:14.995Z,1674686954.995 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2023-01-25T22:49:14.995Z,1674686954.995 [ElevatorServo](FAULT): Elevator failed to initialize
2023-01-25T22:49:14.995Z,1674686954.995 [ElevatorServo] Communications Fault, FailCount= 1
2023-01-25T22:49:14.995Z,1674686954.995 [ElevatorServo](ERROR): Communications Fault
2023-01-25T22:49:14.997Z,1674686954.997 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-01-25T22:49:14.997Z,1674686954.997 [RudderServo](INFO): Powering down
2023-01-25T22:49:15.033Z,1674686955.033 [Waterlinked](INFO): Starting
2023-01-25T22:49:15.063Z,1674686955.063 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-01-25T22:49:15.067Z,1674686955.067 [ElevatorServo](INFO): Powering down
2023-01-25T22:49:15.069Z,1674686955.069 [RudderServo](FAULT): LCB fault:  Current Limiter Activated.
2023-01-25T22:49:15.069Z,1674686955.069 [RudderServo] Hardware Fault, FailCount= 1
2023-01-25T22:49:15.069Z,1674686955.069 [RudderServo](ERROR): Hardware Fault
2023-01-25T22:49:15.413Z,1674686955.413 [Waterlinked](INFO): Starting
2023-01-25T22:49:15.443Z,1674686955.443 [ElevatorServo](FAULT): LCB fault:  Current Limiter Activated.
2023-01-25T22:49:15.443Z,1674686955.443 [ElevatorServo] Hardware Fault, FailCount= 1
2023-01-25T22:49:15.443Z,1674686955.443 [ElevatorServo](ERROR): Hardware Fault
2023-01-25T22:49:15.445Z,1674686955.445 [RudderServo](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:15.564Z,1674686955.564 [RudderServo](DEBUG): Initializing RudderServo.
2023-01-25T22:49:15.565Z,1674686955.565 [ThrusterHE](FAULT): Thruster failed to initialize
2023-01-25T22:49:15.565Z,1674686955.565 [ThrusterHE] Communications Fault, FailCount= 1
2023-01-25T22:49:15.565Z,1674686955.565 [ThrusterHE](ERROR): Communications Fault
2023-01-25T22:49:15.817Z,1674686955.817 [Waterlinked](INFO): Starting
2023-01-25T22:49:15.836Z,1674686955.836 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:15.956Z,1674686955.956 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-01-25T22:49:15.957Z,1674686955.957 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-01-25T22:49:15.957Z,1674686955.957 [ThrusterHE](INFO): Powering down
2023-01-25T22:49:16.281Z,1674686956.281 [Waterlinked](INFO): Starting
2023-01-25T22:49:16.631Z,1674686956.631 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2023-01-25T22:49:16.631Z,1674686956.631 [MassServo] Communications Fault, FailCount= 1
2023-01-25T22:49:16.631Z,1674686956.631 [MassServo](ERROR): Communications Fault
2023-01-25T22:49:16.669Z,1674686956.669 [Waterlinked](INFO): Starting
2023-01-25T22:49:16.693Z,1674686956.693 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-01-25T22:49:16.693Z,1674686956.693 [MassServo](INFO): Powering down
2023-01-25T22:49:17.069Z,1674686957.069 [Waterlinked](INFO): Starting
2023-01-25T22:49:17.100Z,1674686957.100 [MassServo](FAULT): LCB fault:  Current Limiter Activated.
2023-01-25T22:49:17.100Z,1674686957.100 [MassServo] Hardware Fault, FailCount= 1
2023-01-25T22:49:17.100Z,1674686957.100 [MassServo](ERROR): Hardware Fault
2023-01-25T22:49:17.514Z,1674686957.514 [Waterlinked](INFO): Starting
2023-01-25T22:49:17.532Z,1674686957.532 [MassServo](DEBUG): Initializing EZServoServo.
2023-01-25T22:49:17.655Z,1674686957.655 [MassServo](DEBUG): Initializing MassServo.
2023-01-25T22:49:17.890Z,1674686957.890 [Waterlinked](INFO): Starting
2023-01-25T22:49:18.179Z,1674686958.179 [WetLabsBB2FL] Communications Fault, FailCount= 1
2023-01-25T22:49:18.179Z,1674686958.179 [WetLabsBB2FL](ERROR): Communications Fault
2023-01-25T22:49:18.282Z,1674686958.282 [Waterlinked](INFO): Starting
2023-01-25T22:49:18.679Z,1674686958.679 [WetLabsBB2FL](INFO): Powering down
2023-01-25T22:49:18.685Z,1674686958.685 [Waterlinked](INFO): Starting
2023-01-25T22:49:19.088Z,1674686959.088 [WetLabsBB2FL](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T22:49:19.088Z,1674686959.088 [WetLabsBB2FL] Hardware Fault, FailCount= 1
2023-01-25T22:49:19.088Z,1674686959.088 [WetLabsBB2FL](ERROR): Hardware Fault
2023-01-25T22:49:19.090Z,1674686959.090 [Waterlinked](INFO): Starting
2023-01-25T22:49:19.494Z,1674686959.494 [Waterlinked](INFO): Starting
2023-01-25T22:49:19.728Z,1674686959.728 [Radio_Surface](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T22:49:19.728Z,1674686959.728 [Radio_Surface] Hardware Fault, FailCount= 1
2023-01-25T22:49:19.728Z,1674686959.728 [Radio_Surface](ERROR): Hardware Fault
2023-01-25T22:49:19.897Z,1674686959.897 [Waterlinked](INFO): Starting
2023-01-25T22:49:20.131Z,1674686960.131 [Radio_Surface](INFO): Powering down
2023-01-25T22:49:20.301Z,1674686960.301 [Waterlinked](INFO): Starting
2023-01-25T22:49:20.535Z,1674686960.535 [Radio_Surface](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T22:49:20.709Z,1674686960.709 [Waterlinked](INFO): Starting
2023-01-25T22:49:21.110Z,1674686961.110 [Waterlinked](INFO): Starting
2023-01-25T22:49:21.565Z,1674686961.565 [Waterlinked](INFO): Starting
2023-01-25T22:49:21.905Z,1674686961.905 [Waterlinked](INFO): Starting
2023-01-25T22:49:22.306Z,1674686962.306 [Waterlinked](INFO): Starting
2023-01-25T22:49:22.714Z,1674686962.714 [Waterlinked](INFO): Starting
2023-01-25T22:49:23.122Z,1674686963.122 [Waterlinked](INFO): Starting
2023-01-25T22:49:23.525Z,1674686963.525 [Waterlinked](INFO): Starting
2023-01-25T22:49:23.931Z,1674686963.931 [Waterlinked](INFO): Starting
2023-01-25T22:49:24.333Z,1674686964.333 [Waterlinked](INFO): Starting
2023-01-25T22:49:24.731Z,1674686964.731 [Waterlinked](INFO): Starting
2023-01-25T22:49:25.141Z,1674686965.141 [Waterlinked](INFO): Starting
2023-01-25T22:49:25.547Z,1674686965.547 [Waterlinked](INFO): Starting
2023-01-25T22:49:25.935Z,1674686965.935 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2023-01-25T22:49:25.935Z,1674686965.935 [CTD_Seabird] Communications Fault, FailCount= 1
2023-01-25T22:49:25.936Z,1674686965.936 [CTD_Seabird](ERROR): Communications Fault
2023-01-25T22:49:25.949Z,1674686965.949 [Waterlinked](INFO): Starting
2023-01-25T22:49:26.340Z,1674686966.340 [CTD_Seabird](INFO): Powering down
2023-01-25T22:49:26.349Z,1674686966.349 [Waterlinked](INFO): Starting
2023-01-25T22:49:26.758Z,1674686966.758 [Waterlinked](INFO): Starting
2023-01-25T22:49:26.876Z,1674686966.876 [CTD_Seabird](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T22:49:26.876Z,1674686966.876 [CTD_Seabird] Hardware Fault, FailCount= 1
2023-01-25T22:49:26.876Z,1674686966.876 [CTD_Seabird](ERROR): Hardware Fault
2023-01-25T22:49:27.154Z,1674686967.154 [Waterlinked](INFO): Starting
2023-01-25T22:49:27.565Z,1674686967.565 [Waterlinked](INFO): Starting
2023-01-25T22:49:27.969Z,1674686967.969 [Waterlinked](INFO): Starting
2023-01-25T22:49:28.370Z,1674686968.370 [Waterlinked](INFO): Starting
2023-01-25T22:49:28.777Z,1674686968.777 [Waterlinked](INFO): Starting
2023-01-25T22:49:29.181Z,1674686969.181 [Waterlinked](INFO): Starting
2023-01-25T22:49:29.582Z,1674686969.582 [Waterlinked](INFO): Starting
2023-01-25T22:49:29.993Z,1674686969.993 [Waterlinked](INFO): Starting
2023-01-25T22:49:30.394Z,1674686970.394 [Waterlinked](INFO): Starting
2023-01-25T22:49:30.804Z,1674686970.804 [Waterlinked](INFO): Starting
2023-01-25T22:49:31.191Z,1674686971.191 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-01-25T22:49:31.191Z,1674686971.191 [AHRS_M2] Data Fault, FailCount= 1
2023-01-25T22:49:31.191Z,1674686971.191 [AHRS_M2](ERROR): Data Fault
2023-01-25T22:49:31.201Z,1674686971.201 [Waterlinked](INFO): Starting
2023-01-25T22:49:31.585Z,1674686971.585 [AHRS_M2](INFO): Powering down
2023-01-25T22:49:31.669Z,1674686971.669 [Waterlinked](INFO): Starting
2023-01-25T22:49:31.999Z,1674686971.999 [Waterlinked](INFO): Starting
2023-01-25T22:49:32.397Z,1674686972.397 [Waterlinked](INFO): Starting
2023-01-25T22:49:32.806Z,1674686972.806 [Waterlinked](INFO): Starting
2023-01-25T22:49:33.210Z,1674686973.210 [Waterlinked](INFO): Starting
2023-01-25T22:49:33.615Z,1674686973.615 [Waterlinked](INFO): Starting
2023-01-25T22:49:34.018Z,1674686974.018 [Waterlinked](INFO): Starting
2023-01-25T22:49:34.415Z,1674686974.415 [Waterlinked](INFO): Starting
2023-01-25T22:49:34.825Z,1674686974.825 [Waterlinked](INFO): Starting
2023-01-25T22:49:35.226Z,1674686975.226 [Waterlinked](INFO): Starting
2023-01-25T22:49:35.627Z,1674686975.627 [Waterlinked](INFO): Starting
2023-01-25T22:49:36.033Z,1674686976.033 [Waterlinked](INFO): Starting
2023-01-25T22:49:36.436Z,1674686976.436 [Waterlinked](INFO): Starting
2023-01-25T22:49:36.845Z,1674686976.845 [Waterlinked](INFO): Starting
2023-01-25T22:49:37.245Z,1674686977.245 [Waterlinked](INFO): Starting
2023-01-25T22:49:37.647Z,1674686977.647 [Waterlinked](INFO): Starting
2023-01-25T22:49:38.057Z,1674686978.057 [Waterlinked](INFO): Starting
2023-01-25T22:49:38.461Z,1674686978.461 [Waterlinked](INFO): Starting
2023-01-25T22:49:38.867Z,1674686978.867 [Waterlinked](INFO): Starting
2023-01-25T22:49:39.263Z,1674686979.263 [Waterlinked](INFO): Starting
2023-01-25T22:49:39.673Z,1674686979.673 [Waterlinked](INFO): Starting
2023-01-25T22:49:40.077Z,1674686980.077 [Waterlinked](INFO): Starting
2023-01-25T22:49:40.477Z,1674686980.477 [Waterlinked](INFO): Starting
2023-01-25T22:49:40.881Z,1674686980.881 [Waterlinked](INFO): Starting
2023-01-25T22:49:41.282Z,1674686981.282 [NAL9602](INFO): Powering up NAL9602
2023-01-25T22:49:41.285Z,1674686981.285 [Waterlinked](INFO): Starting
2023-01-25T22:49:41.285Z,1674686981.285 [Waterlinked](FAULT): Could not initialize.
2023-01-25T22:49:41.285Z,1674686981.285 [Waterlinked] Communications Fault, FailCount= 1
2023-01-25T22:49:41.285Z,1674686981.285 [Waterlinked](ERROR): Communications Fault
2023-01-25T22:49:41.295Z,1674686981.295 [SBIT](IMPORTANT): Beginning Startup BIT
2023-01-25T22:49:41.687Z,1674686981.687 [Waterlinked](INFO): Stop
2023-01-25T22:49:41.760Z,1674686981.760 [Waterlinked](INFO): Powering down
2023-01-25T22:49:42.097Z,1674686982.097 [Waterlinked](INFO): Stopping
2023-01-25T22:49:42.098Z,1674686982.098 [Waterlinked](ERROR): LCB error: Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T22:49:42.497Z,1674686982.497 [Waterlinked](INFO): Stopped
2023-01-25T22:49:43.951Z,1674686983.951 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-01-25T22:49:54.626Z,1674686994.626 [SBIT](ERROR): Could not read elevatorAngleReader_.
2023-01-25T22:49:54.626Z,1674686994.626 [SBIT](ERROR): Could not read rudderAngleReader_.
2023-01-25T22:49:54.626Z,1674686994.626 [SBIT](ERROR): Could not read massPosReader_.
2023-01-25T22:50:01.893Z,1674687001.893 [NAL9602](ERROR): NAL9602 initialization error.
2023-01-25T22:50:01.893Z,1674687001.893 [NAL9602] Communications Fault, FailCount= 1
2023-01-25T22:50:01.893Z,1674687001.893 [NAL9602](ERROR): Communications Fault
2023-01-25T22:50:02.294Z,1674687002.294 [NAL9602](INFO): Powering down
2023-01-25T22:50:13.124Z,1674687013.124 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2023-01-25T22:50:13.124Z,1674687013.124 [DAT] Communications Fault, FailCount= 1
2023-01-25T22:50:13.124Z,1674687013.124 [DAT](ERROR): Communications Fault
2023-01-25T22:50:13.528Z,1674687013.528 [DAT](INFO): Powering down
2023-01-25T22:50:14.012Z,1674687014.012 [DAT](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T22:50:14.012Z,1674687014.012 [DAT] Hardware Fault, FailCount= 1
2023-01-25T22:50:14.012Z,1674687014.012 [DAT](ERROR): Hardware Fault
2023-01-25T22:50:21.319Z,1674687021.319 [SBIT](ERROR): Could not read elevatorAngleReader_.
2023-01-25T22:50:21.319Z,1674687021.319 [SBIT](ERROR): Could not read rudderAngleReader_.
2023-01-25T22:50:21.319Z,1674687021.319 [SBIT](ERROR): Could not read massPosReader_.
2023-01-25T22:50:21.319Z,1674687021.319 [SBIT](FAULT): Control surface position failure.
2023-01-25T22:50:35.045Z,1674687035.045 [SBIT](ERROR): Could not read elevatorAngleReader_.
2023-01-25T22:50:35.045Z,1674687035.045 [SBIT](ERROR): Could not read rudderAngleReader_.
2023-01-25T22:50:35.045Z,1674687035.045 [SBIT](ERROR): Could not read massPosReader_.
2023-01-25T22:50:35.046Z,1674687035.046 [SBIT](FAULT): Control surface position failure.
2023-01-25T22:50:35.432Z,1674687035.432 [SBIT](CRITICAL): SBIT FAILED
2023-01-25T22:50:35.432Z,1674687035.432 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-01-25T22:50:35.835Z,1674687035.835 [MissionManager](IMPORTANT): Started mission Startup
2023-01-25T22:50:35.835Z,1674687035.835 [Startup] Running Loop=1
2023-01-25T22:50:35.835Z,1674687035.835 [Startup](DEBUG): Aggregate::initialize Startup
2023-01-25T22:50:35.835Z,1674687035.835 [Startup:A.GoToSurface] Running Loop=1
2023-01-25T22:50:35.836Z,1674687035.836 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-01-25T22:50:35.836Z,1674687035.836 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-01-25T22:50:35.837Z,1674687035.837 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-01-25T22:50:35.837Z,1674687035.837 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-01-25T22:50:35.837Z,1674687035.837 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-01-25T22:50:35.838Z,1674687035.838 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-01-25T22:50:35.839Z,1674687035.839 [Startup:StartupSatComms] Running Loop=1
2023-01-25T22:50:35.839Z,1674687035.839 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-01-25T22:50:35.839Z,1674687035.839 [Startup:StartupSatComms:A] Running Loop=1
2023-01-25T22:50:36.241Z,1674687036.241 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-01-25T22:51:13.016Z,1674687073.016 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-01-25T22:51:13.016Z,1674687073.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-01-25T22:51:13.016Z,1674687073.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-25T22:51:13.018Z,1674687073.018 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-01-25T22:51:13.018Z,1674687073.018 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-01-25T22:51:13.018Z,1674687073.018 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-01-25T22:51:36.030Z,1674687096.030 [Startup:StartupSatComms:A](INFO): Timed out from 2023-01-25T22:50:35.8Z
2023-01-25T22:51:36.030Z,1674687096.030 [Startup:StartupSatComms:A] Stopped
2023-01-25T22:51:36.030Z,1674687096.030 [Startup:StartupSatComms:B] Running Loop=1
2023-01-25T22:51:36.432Z,1674687096.432 [Startup:StartupSatComms:B](DEBUG): Initialize R