2023-01-25T21:54:25.563Z,1674683665.563 [Supervisor](DEBUG): Initializing supervisor.
2023-01-25T21:54:25.567Z,1674683665.567 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-01-25T21:54:25.568Z,1674683665.568 [SyncHandler](INFO): Protected caller Thread ID is 4663
2023-01-25T21:54:25.568Z,1674683665.568 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-01-25T21:54:25.569Z,1674683665.569 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-01-25T21:54:25.570Z,1674683665.570 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4664
2023-01-25T21:54:25.574Z,1674683665.574 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-01-25T21:54:25.591Z,1674683665.591 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-01-25T21:54:25.592Z,1674683665.592 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-01-25T21:54:25.592Z,1674683665.592 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4665
2023-01-25T21:54:25.596Z,1674683665.596 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-01-25T21:54:25.597Z,1674683665.597 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-01-25T21:54:25.597Z,1674683665.597 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4666
2023-01-25T21:54:25.599Z,1674683665.599 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-01-25T21:54:25.600Z,1674683665.600 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-01-25T21:54:25.601Z,1674683665.601 [logger ThreadHandler](INFO): Protected caller Thread ID is 4667
2023-01-25T21:54:25.604Z,1674683665.604 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-01-25T21:54:25.605Z,1674683665.605 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-01-25T21:54:25.606Z,1674683665.606 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-01-25T21:54:25.813Z,1674683665.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-01-25T21:54:25.814Z,1674683665.814 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-01-25T21:54:25.923Z,1674683665.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-01-25T21:54:25.924Z,1674683665.924 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-01-25T21:54:26.019Z,1674683666.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-01-25T21:54:26.020Z,1674683666.020 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-01-25T21:54:26.468Z,1674683666.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-01-25T21:54:26.468Z,1674683666.468 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-01-25T21:54:26.805Z,1674683666.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-01-25T21:54:26.806Z,1674683666.806 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-01-25T21:54:27.133Z,1674683667.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-01-25T21:54:27.133Z,1674683667.133 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-01-25T21:54:27.317Z,1674683667.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-01-25T21:54:27.318Z,1674683667.318 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-01-25T21:54:27.400Z,1674683667.400 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-01-25T21:54:27.480Z,1674683667.480 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-01-25T21:54:27.865Z,1674683667.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-01-25T21:54:27.865Z,1674683667.865 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-01-25T21:54:28.219Z,1674683668.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-01-25T21:54:28.219Z,1674683668.219 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-01-25T21:54:28.719Z,1674683668.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-01-25T21:54:28.720Z,1674683668.720 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-01-25T21:54:28.927Z,1674683668.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-01-25T21:54:28.928Z,1674683668.928 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-01-25T21:54:29.161Z,1674683669.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-01-25T21:54:29.161Z,1674683669.161 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-01-25T21:54:29.297Z,1674683669.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-01-25T21:54:29.298Z,1674683669.298 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-01-25T21:54:30.037Z,1674683670.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-01-25T21:54:30.039Z,1674683670.039 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2023-01-25T21:54:30.040Z,1674683670.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2023-01-25T21:54:30.125Z,1674683670.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2023-01-25T21:54:30.385Z,1674683670.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2023-01-25T21:54:31.101Z,1674683671.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-01-25T21:54:31.101Z,1674683671.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2023-01-25T21:54:31.222Z,1674683671.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2023-01-25T21:54:31.322Z,1674683671.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2023-01-25T21:54:31.414Z,1674683671.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2023-01-25T21:54:31.525Z,1674683671.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2023-01-25T21:54:31.619Z,1674683671.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2023-01-25T21:54:31.704Z,1674683671.704 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2023-01-25T21:54:31.803Z,1674683671.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2023-01-25T21:54:31.959Z,1674683671.959 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2023-01-25T21:54:31.959Z,1674683671.959 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-01-25T21:54:31.961Z,1674683671.961 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-01-25T21:54:32.010Z,1674683672.010 [DepthRateCalculator] Loaded
2023-01-25T21:54:32.011Z,1674683672.011 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-01-25T21:54:32.016Z,1674683672.016 [PitchRateCalculator] Loaded
2023-01-25T21:54:32.016Z,1674683672.016 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-01-25T21:54:32.026Z,1674683672.026 [SpeedCalculator] Loaded
2023-01-25T21:54:32.027Z,1674683672.027 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-01-25T21:54:32.031Z,1674683672.031 [YawRateCalculator] Loaded
2023-01-25T21:54:32.032Z,1674683672.032 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-01-25T21:54:32.052Z,1674683672.052 [ElevatorOffsetCalculator] Loaded
2023-01-25T21:54:32.053Z,1674683672.053 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-01-25T21:54:32.053Z,1674683672.053 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-01-25T21:54:32.054Z,1674683672.054 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-01-25T21:54:32.079Z,1674683672.079 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-01-25T21:54:32.079Z,1674683672.079 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-01-25T21:54:32.089Z,1674683672.089 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-01-25T21:54:32.090Z,1674683672.090 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-01-25T21:54:32.488Z,1674683672.488 [AHRS_M2] Loaded
2023-01-25T21:54:32.488Z,1674683672.488 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-01-25T21:54:32.523Z,1674683672.523 [BackseatComponent] Loaded
2023-01-25T21:54:32.523Z,1674683672.523 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-01-25T21:54:32.524Z,1674683672.524 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4083D4E0
2023-01-25T21:54:32.524Z,1674683672.524 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4750
2023-01-25T21:54:32.527Z,1674683672.527 [LcmUniversalReporter] Loaded
2023-01-25T21:54:32.527Z,1674683672.527 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-01-25T21:54:33.313Z,1674683673.313 [BPC1] Loaded
2023-01-25T21:54:33.313Z,1674683673.313 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-01-25T21:54:33.391Z,1674683673.391 [DataOverHttps] Loaded
2023-01-25T21:54:33.391Z,1674683673.391 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-01-25T21:54:33.392Z,1674683673.392 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4086D4E0
2023-01-25T21:54:33.393Z,1674683673.393 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4751
2023-01-25T21:54:33.413Z,1674683673.413 [Depth_Keller] Loaded
2023-01-25T21:54:33.413Z,1674683673.413 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-01-25T21:54:33.418Z,1674683673.418 [DropWeight] Loaded
2023-01-25T21:54:33.418Z,1674683673.418 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-01-25T21:54:33.479Z,1674683673.479 [NAL9602] Loaded
2023-01-25T21:54:33.480Z,1674683673.480 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-01-25T21:54:33.509Z,1674683673.509 [Onboard] Loaded
2023-01-25T21:54:33.509Z,1674683673.509 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-01-25T21:54:33.510Z,1674683673.510 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4089D4E0
2023-01-25T21:54:33.510Z,1674683673.510 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4752
2023-01-25T21:54:33.516Z,1674683673.516 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2023-01-25T21:54:33.527Z,1674683673.527 [PowerOnly] Loaded
2023-01-25T21:54:33.527Z,1674683673.527 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-01-25T21:54:33.538Z,1674683673.538 [Power24vConverter] Loaded
2023-01-25T21:54:33.539Z,1674683673.539 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-01-25T21:54:33.553Z,1674683673.553 [Radio_Surface] Loaded
2023-01-25T21:54:33.553Z,1674683673.553 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-01-25T21:54:33.554Z,1674683673.554 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408CD4E0
2023-01-25T21:54:33.554Z,1674683673.554 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4753
2023-01-25T21:54:33.642Z,1674683673.642 [DAT] Loaded
2023-01-25T21:54:33.643Z,1674683673.643 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-01-25T21:54:33.644Z,1674683673.644 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-01-25T21:54:33.644Z,1674683673.644 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4754
2023-01-25T21:54:33.665Z,1674683673.665 [Waterlinked] Loaded
2023-01-25T21:54:33.665Z,1674683673.665 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2023-01-25T21:54:33.666Z,1674683673.666 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-01-25T21:54:33.666Z,1674683673.666 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-01-25T21:54:33.773Z,1674683673.773 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-01-25T21:54:33.773Z,1674683673.773 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-01-25T21:54:33.813Z,1674683673.813 [DeadReckonUsingSpeedCalculator] Loaded
2023-01-25T21:54:33.814Z,1674683673.814 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-01-25T21:54:33.827Z,1674683673.827 [NavChart] Loaded
2023-01-25T21:54:33.827Z,1674683673.827 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-01-25T21:54:33.833Z,1674683673.833 [UniversalFixResidualReporter] Loaded
2023-01-25T21:54:33.833Z,1674683673.833 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-01-25T21:54:33.834Z,1674683673.834 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-01-25T21:54:33.834Z,1674683673.834 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-01-25T21:54:33.965Z,1674683673.965 [SBIT](DEBUG): Construct Startup Built In Test.
2023-01-25T21:54:33.979Z,1674683673.979 [SBIT] Loaded
2023-01-25T21:54:33.979Z,1674683673.979 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-01-25T21:54:33.982Z,1674683673.982 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-01-25T21:54:33.995Z,1674683673.995 [IBIT] Loaded
2023-01-25T21:54:33.995Z,1674683673.995 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-01-25T21:54:34.001Z,1674683674.001 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-01-25T21:54:34.102Z,1674683674.102 [CBIT] Loaded
2023-01-25T21:54:34.103Z,1674683674.103 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-01-25T21:54:34.103Z,1674683674.103 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-01-25T21:54:34.104Z,1674683674.104 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-01-25T21:54:34.265Z,1674683674.265 [CTD_Seabird] Loaded
2023-01-25T21:54:34.266Z,1674683674.266 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-01-25T21:54:34.267Z,1674683674.267 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A014E0
2023-01-25T21:54:34.267Z,1674683674.267 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4755
2023-01-25T21:54:34.287Z,1674683674.287 [PAR_Licor] Loaded
2023-01-25T21:54:34.287Z,1674683674.287 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-01-25T21:54:34.318Z,1674683674.318 [WetLabsBB2FL] Loaded
2023-01-25T21:54:34.318Z,1674683674.318 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-01-25T21:54:34.319Z,1674683674.319 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0
2023-01-25T21:54:34.320Z,1674683674.320 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4756
2023-01-25T21:54:34.321Z,1674683674.321 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-01-25T21:54:34.321Z,1674683674.321 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-01-25T21:54:34.448Z,1674683674.448 [BuoyancyServo] Loaded
2023-01-25T21:54:34.448Z,1674683674.448 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-01-25T21:54:34.471Z,1674683674.471 [ElevatorServo] Loaded
2023-01-25T21:54:34.471Z,1674683674.471 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-01-25T21:54:34.491Z,1674683674.491 [MassServo] Loaded
2023-01-25T21:54:34.492Z,1674683674.492 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-01-25T21:54:34.512Z,1674683674.512 [RudderServo] Loaded
2023-01-25T21:54:34.512Z,1674683674.512 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-01-25T21:54:34.528Z,1674683674.528 [ThrusterHE] Loaded
2023-01-25T21:54:34.528Z,1674683674.528 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-01-25T21:54:34.528Z,1674683674.528 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-01-25T21:54:34.529Z,1674683674.529 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-01-25T21:54:34.578Z,1674683674.578 [VerticalControl](DEBUG): Construct VerticalControl.
2023-01-25T21:54:34.628Z,1674683674.628 [VerticalControl] Loaded
2023-01-25T21:54:34.628Z,1674683674.628 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-01-25T21:54:34.631Z,1674683674.631 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-01-25T21:54:34.667Z,1674683674.667 [HorizontalControl] Loaded
2023-01-25T21:54:34.668Z,1674683674.668 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-01-25T21:54:34.670Z,1674683674.670 [SpeedControl](DEBUG): Construct SpeedControl.
2023-01-25T21:54:34.672Z,1674683674.672 [SpeedControl] Loaded
2023-01-25T21:54:34.672Z,1674683674.672 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-01-25T21:54:34.675Z,1674683674.675 [LoopControl](DEBUG): Construct LoopControl.
2023-01-25T21:54:34.675Z,1674683674.675 [LoopControl] Loaded
2023-01-25T21:54:34.676Z,1674683674.676 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-01-25T21:54:34.676Z,1674683674.676 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-01-25T21:54:34.677Z,1674683674.677 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-01-25T21:54:34.773Z,1674683674.773 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-01-25T21:54:34.773Z,1674683674.773 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-01-25T21:54:34.915Z,1674683674.915 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-01-25T21:54:34.916Z,1674683674.916 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-01-25T21:54:35.214Z,1674683675.214 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-01-25T21:54:35.214Z,1674683675.214 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-01-25T21:54:35.269Z,1674683675.269 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-01-25T21:54:35.275Z,1674683675.275 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-01-25T21:54:35.278Z,1674683675.278 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-01-25T21:54:35.289Z,1674683675.289 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-01-25T21:54:35.290Z,1674683675.290 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD44E0
2023-01-25T21:54:35.290Z,1674683675.290 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4757
2023-01-25T21:54:35.294Z,1674683675.294 [Supervisor](INFO): Main Thread ID is 4662
2023-01-25T21:54:35.294Z,1674683675.294 [Supervisor](DEBUG): Running supervisor.
2023-01-25T21:54:35.295Z,1674683675.295 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4758
2023-01-25T21:54:35.295Z,1674683675.295 [CommandExec](INFO): Initializing the command executive.
2023-01-25T21:54:35.297Z,1674683675.297 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4759
2023-01-25T21:54:35.300Z,1674683675.300 [controlThread ThreadHandler](INFO): Handler Thread ID is 4760
2023-01-25T21:54:35.301Z,1674683675.301 [controlThread](DEBUG): Initializing ControlThread
2023-01-25T21:54:35.301Z,1674683675.301 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-01-25T21:54:35.302Z,1674683675.302 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-01-25T21:54:35.302Z,1674683675.302 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-01-25T21:54:35.303Z,1674683675.303 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-01-25T21:54:35.303Z,1674683675.303 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-01-25T21:54:35.310Z,1674683675.310 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-01-25T21:54:35.310Z,1674683675.310 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-01-25T21:54:35.310Z,1674683675.310 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-01-25T21:54:35.311Z,1674683675.311 [SBIT](INFO): Initialize SBIT Component.
2023-01-25T21:54:35.311Z,1674683675.311 [SBIT](IMPORTANT): git: 2022-12-14-5-g868688940
2023-01-25T21:54:35.311Z,1674683675.311 [SBIT](INFO): git hash: 868688940ec3a995afef730fa44917767e114440
2023-01-25T21:54:35.312Z,1674683675.312 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-01-25T21:54:35.313Z,1674683675.313 [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-25T21:54:35.314Z,1674683675.314 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2023-01-25T21:54:35.314Z,1674683675.314 [IBIT](INFO): Initialize IBIT Component.
2023-01-25T21:54:35.316Z,1674683675.316 [CBIT](DEBUG): Initialize CBIT Component.
2023-01-25T21:54:35.316Z,1674683675.316 [logger ThreadHandler](INFO): Handler Thread ID is 4761
2023-01-25T21:54:35.327Z,1674683675.327 [CBIT](DEBUG): Initialized mux pins.
2023-01-25T21:54:35.327Z,1674683675.327 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2023-01-25T21:54:35.327Z,1674683675.327 [CBIT](DEBUG): Initializing the watchdog timer.
2023-01-25T21:54:35.335Z,1674683675.335 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4762
2023-01-25T21:54:35.347Z,1674683675.347 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4763
2023-01-25T21:54:35.348Z,1674683675.348 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-01-25T21:54:35.351Z,1674683675.351 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2023-01-25T21:54:35.351Z,1674683675.351 [CBIT] Hardware Fault, FailCount= 1
2023-01-25T21:54:35.351Z,1674683675.351 [CBIT](ERROR): Hardware Fault
2023-01-25T21:54:35.352Z,1674683675.352 [CBIT](DEBUG): Initializing heartbeat.
2023-01-25T21:54:35.359Z,1674683675.359 [Onboard ThreadHandler](INFO): Handler Thread ID is 4764
2023-01-25T21:54:35.376Z,1674683675.376 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4765
2023-01-25T21:54:35.400Z,1674683675.400 [DAT ThreadHandler](INFO): Handler Thread ID is 4766
2023-01-25T21:54:35.401Z,1674683675.401 [DAT](INFO): Powering up
2023-01-25T21:54:35.401Z,1674683675.401 [DAT](DEBUG): Initializing DAT.
2023-01-25T21:54:35.404Z,1674683675.404 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4768
2023-01-25T21:54:35.405Z,1674683675.405 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-01-25T21:54:35.408Z,1674683675.408 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4770
2023-01-25T21:54:35.411Z,1674683675.411 [WetLabsBB2FL](INFO): Powering up
2023-01-25T21:54:35.413Z,1674683675.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4772
2023-01-25T21:54:35.416Z,1674683675.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-01-25T21:54:35.416Z,1674683675.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-01-25T21:54:35.416Z,1674683675.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-01-25T21:54:35.416Z,1674683675.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-01-25T21:54:35.417Z,1674683675.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-01-25T21:54:35.417Z,1674683675.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-01-25T21:54:35.417Z,1674683675.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-01-25T21:54:35.417Z,1674683675.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-01-25T21:54:35.423Z,1674683675.423 [CBIT](DEBUG): Deactivating GF circuits.
2023-01-25T21:54:35.423Z,1674683675.423 [CBIT](DEBUG): Deactivating emergency mode.
2023-01-25T21:54:35.459Z,1674683675.459 [CBIT](DEBUG): Backplane powered.
2023-01-25T21:54:35.464Z,1674683675.464 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-01-25T21:54:35.466Z,1674683675.466 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-01-25T21:54:35.467Z,1674683675.467 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-01-25T21:54:35.468Z,1674683675.468 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-01-25T21:54:35.468Z,1674683675.468 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-01-25T21:54:35.483Z,1674683675.483 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-25T21:54:35.506Z,1674683675.506 [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-25T21:54:35.515Z,1674683675.515 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-01-25T21:54:35.598Z,1674683675.598 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-01-25T21:54:35.608Z,1674683675.608 [Default:A.Wait](DEBUG): Construct Wait.
2023-01-25T21:54:35.610Z,1674683675.610 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-25T21:54:35.655Z,1674683675.655 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-01-25T21:54:35.665Z,1674683675.665 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-01-25T21:54:35.700Z,1674683675.700 [Default:E.Execute](DEBUG): Construct Execute.
2023-01-25T21:54:35.707Z,1674683675.707 [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-25T21:54:35.713Z,1674683675.713 [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-25T21:54:35.728Z,1674683675.728 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-01-25T21:54:35.783Z,1674683675.783 [Radio_Surface](INFO): Powering up
2023-01-25T21:54:35.791Z,1674683675.791 [Power24vConverter](INFO): Powering up.
2023-01-25T21:54:35.792Z,1674683675.792 [Waterlinked](INFO): Start
2023-01-25T21:54:35.792Z,1674683675.792 [Waterlinked](IMPORTANT): Expecting PD6 message format
2023-01-25T21:54:35.861Z,1674683675.861 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:35.976Z,1674683675.976 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-01-25T21:54:35.977Z,1674683675.977 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:35.986Z,1674683675.986 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-01-25T21:54:35.990Z,1674683675.990 [MassServo](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:36.002Z,1674683676.002 [MassServo](DEBUG): Initializing MassServo.
2023-01-25T21:54:36.006Z,1674683676.006 [RudderServo](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:36.015Z,1674683676.015 [RudderServo](DEBUG): Initializing RudderServo.
2023-01-25T21:54:36.016Z,1674683676.016 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:36.035Z,1674683676.035 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-01-25T21:54:36.036Z,1674683676.036 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-01-25T21:54:36.036Z,1674683676.036 [CBIT](DEBUG): Powering off loads.
2023-01-25T21:54:36.047Z,1674683676.047 [CBIT](DEBUG): Disabling WDT.
2023-01-25T21:54:36.059Z,1674683676.059 [CBIT](DEBUG): Opening all GF detection circuits.
2023-01-25T21:54:36.159Z,1674683676.159 [Waterlinked](INFO): Starting
2023-01-25T21:54:36.528Z,1674683676.528 [Waterlinked](INFO): Starting
2023-01-25T21:54:36.863Z,1674683676.863 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-01-25T21:54:36.863Z,1674683676.863 [RudderServo](FAULT): Rudder failed to initialize
2023-01-25T21:54:36.863Z,1674683676.863 [RudderServo] Communications Fault, FailCount= 1
2023-01-25T21:54:36.863Z,1674683676.863 [RudderServo](ERROR): Communications Fault
2023-01-25T21:54:36.933Z,1674683676.933 [Waterlinked](INFO): Starting
2023-01-25T21:54:37.287Z,1674683677.287 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2023-01-25T21:54:37.287Z,1674683677.287 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2023-01-25T21:54:37.287Z,1674683677.287 [BuoyancyServo] Communications Fault, FailCount= 1
2023-01-25T21:54:37.287Z,1674683677.287 [BuoyancyServo](ERROR): Communications Fault
2023-01-25T21:54:37.599Z,1674683677.599 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2023-01-25T21:54:37.599Z,1674683677.599 [ElevatorServo](FAULT): Elevator failed to initialize
2023-01-25T21:54:37.599Z,1674683677.599 [ElevatorServo] Communications Fault, FailCount= 1
2023-01-25T21:54:37.599Z,1674683677.599 [ElevatorServo](ERROR): Communications Fault
2023-01-25T21:54:37.600Z,1674683677.600 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-01-25T21:54:37.600Z,1674683677.600 [RudderServo](INFO): Powering down
2023-01-25T21:54:37.641Z,1674683677.641 [Waterlinked](INFO): Starting
2023-01-25T21:54:37.662Z,1674683677.662 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-01-25T21:54:37.668Z,1674683677.668 [ElevatorServo](INFO): Powering down
2023-01-25T21:54:37.669Z,1674683677.669 [RudderServo](FAULT): LCB fault:  Current Limiter Activated.
2023-01-25T21:54:37.669Z,1674683677.669 [RudderServo] Hardware Fault, FailCount= 1
2023-01-25T21:54:37.669Z,1674683677.669 [RudderServo](ERROR): Hardware Fault
2023-01-25T21:54:38.016Z,1674683678.016 [Waterlinked](INFO): Starting
2023-01-25T21:54:38.047Z,1674683678.047 [ElevatorServo](FAULT): LCB fault:  Current Limiter Activated.
2023-01-25T21:54:38.047Z,1674683678.047 [ElevatorServo] Hardware Fault, FailCount= 1
2023-01-25T21:54:38.047Z,1674683678.047 [ElevatorServo](ERROR): Hardware Fault
2023-01-25T21:54:38.048Z,1674683678.048 [RudderServo](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:38.168Z,1674683678.168 [RudderServo](DEBUG): Initializing RudderServo.
2023-01-25T21:54:38.169Z,1674683678.169 [ThrusterHE](FAULT): Thruster failed to initialize
2023-01-25T21:54:38.169Z,1674683678.169 [ThrusterHE] Communications Fault, FailCount= 1
2023-01-25T21:54:38.169Z,1674683678.169 [ThrusterHE](ERROR): Communications Fault
2023-01-25T21:54:38.421Z,1674683678.421 [Waterlinked](INFO): Starting
2023-01-25T21:54:38.459Z,1674683678.459 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:38.580Z,1674683678.580 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-01-25T21:54:38.581Z,1674683678.581 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-01-25T21:54:38.581Z,1674683678.581 [ThrusterHE](INFO): Powering down
2023-01-25T21:54:38.885Z,1674683678.885 [Waterlinked](INFO): Starting
2023-01-25T21:54:39.203Z,1674683679.203 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2023-01-25T21:54:39.203Z,1674683679.203 [MassServo] Communications Fault, FailCount= 1
2023-01-25T21:54:39.203Z,1674683679.203 [MassServo](ERROR): Communications Fault
2023-01-25T21:54:39.250Z,1674683679.250 [Waterlinked](INFO): Starting
2023-01-25T21:54:39.271Z,1674683679.271 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-01-25T21:54:39.271Z,1674683679.271 [MassServo](INFO): Powering down
2023-01-25T21:54:39.657Z,1674683679.657 [Waterlinked](INFO): Starting
2023-01-25T21:54:39.673Z,1674683679.673 [MassServo](FAULT): LCB fault:  Current Limiter Activated.
2023-01-25T21:54:39.673Z,1674683679.673 [MassServo] Hardware Fault, FailCount= 1
2023-01-25T21:54:39.673Z,1674683679.673 [MassServo](ERROR): Hardware Fault
2023-01-25T21:54:40.101Z,1674683680.101 [Waterlinked](INFO): Starting
2023-01-25T21:54:40.120Z,1674683680.120 [MassServo](DEBUG): Initializing EZServoServo.
2023-01-25T21:54:40.240Z,1674683680.240 [MassServo](DEBUG): Initializing MassServo.
2023-01-25T21:54:40.466Z,1674683680.466 [Waterlinked](INFO): Starting
2023-01-25T21:54:40.699Z,1674683680.699 [WetLabsBB2FL] Communications Fault, FailCount= 1
2023-01-25T21:54:40.699Z,1674683680.699 [WetLabsBB2FL](ERROR): Communications Fault
2023-01-25T21:54:40.869Z,1674683680.869 [Waterlinked](INFO): Starting
2023-01-25T21:54:41.199Z,1674683681.199 [WetLabsBB2FL](INFO): Powering down
2023-01-25T21:54:41.273Z,1674683681.273 [Waterlinked](INFO): Starting
2023-01-25T21:54:41.603Z,1674683681.603 [WetLabsBB2FL](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T21:54:41.604Z,1674683681.604 [WetLabsBB2FL] Hardware Fault, FailCount= 1
2023-01-25T21:54:41.604Z,1674683681.604 [WetLabsBB2FL](ERROR): Hardware Fault
2023-01-25T21:54:41.677Z,1674683681.677 [Waterlinked](INFO): Starting
2023-01-25T21:54:42.081Z,1674683682.081 [Waterlinked](INFO): Starting
2023-01-25T21:54:42.248Z,1674683682.248 [Radio_Surface](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T21:54:42.248Z,1674683682.248 [Radio_Surface] Hardware Fault, FailCount= 1
2023-01-25T21:54:42.248Z,1674683682.248 [Radio_Surface](ERROR): Hardware Fault
2023-01-25T21:54:42.485Z,1674683682.485 [Waterlinked](INFO): Starting
2023-01-25T21:54:42.651Z,1674683682.651 [Radio_Surface](INFO): Powering down
2023-01-25T21:54:42.889Z,1674683682.889 [Waterlinked](INFO): Starting
2023-01-25T21:54:43.055Z,1674683683.055 [Radio_Surface](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T21:54:43.297Z,1674683683.297 [Waterlinked](INFO): Starting
2023-01-25T21:54:43.697Z,1674683683.697 [Waterlinked](INFO): Starting
2023-01-25T21:54:44.161Z,1674683684.161 [Waterlinked](INFO): Starting
2023-01-25T21:54:44.490Z,1674683684.490 [Waterlinked](INFO): Starting
2023-01-25T21:54:44.901Z,1674683684.901 [Waterlinked](INFO): Starting
2023-01-25T21:54:45.306Z,1674683685.306 [Waterlinked](INFO): Starting
2023-01-25T21:54:45.705Z,1674683685.705 [Waterlinked](INFO): Starting
2023-01-25T21:54:46.113Z,1674683686.113 [Waterlinked](INFO): Starting
2023-01-25T21:54:46.517Z,1674683686.517 [Waterlinked](INFO): Starting
2023-01-25T21:54:46.922Z,1674683686.922 [Waterlinked](INFO): Starting
2023-01-25T21:54:47.318Z,1674683687.318 [Waterlinked](INFO): Starting
2023-01-25T21:54:47.729Z,1674683687.729 [Waterlinked](INFO): Starting
2023-01-25T21:54:48.133Z,1674683688.133 [Waterlinked](INFO): Starting
2023-01-25T21:54:48.455Z,1674683688.455 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2023-01-25T21:54:48.455Z,1674683688.455 [CTD_Seabird] Communications Fault, FailCount= 1
2023-01-25T21:54:48.455Z,1674683688.455 [CTD_Seabird](ERROR): Communications Fault
2023-01-25T21:54:48.540Z,1674683688.540 [Waterlinked](INFO): Starting
2023-01-25T21:54:48.860Z,1674683688.860 [CTD_Seabird](INFO): Powering down
2023-01-25T21:54:48.937Z,1674683688.937 [Waterlinked](INFO): Starting
2023-01-25T21:54:49.345Z,1674683689.345 [Waterlinked](INFO): Starting
2023-01-25T21:54:49.391Z,1674683689.391 [CTD_Seabird](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T21:54:49.391Z,1674683689.391 [CTD_Seabird] Hardware Fault, FailCount= 1
2023-01-25T21:54:49.391Z,1674683689.391 [CTD_Seabird](ERROR): Hardware Fault
2023-01-25T21:54:49.742Z,1674683689.742 [Waterlinked](INFO): Starting
2023-01-25T21:54:50.154Z,1674683690.154 [Waterlinked](INFO): Starting
2023-01-25T21:54:50.557Z,1674683690.557 [Waterlinked](INFO): Starting
2023-01-25T21:54:50.961Z,1674683690.961 [Waterlinked](INFO): Starting
2023-01-25T21:54:51.361Z,1674683691.361 [Waterlinked](INFO): Starting
2023-01-25T21:54:51.770Z,1674683691.770 [Waterlinked](INFO): Starting
2023-01-25T21:54:52.166Z,1674683692.166 [Waterlinked](INFO): Starting
2023-01-25T21:54:52.577Z,1674683692.577 [Waterlinked](INFO): Starting
2023-01-25T21:54:52.981Z,1674683692.981 [Waterlinked](INFO): Starting
2023-01-25T21:54:53.387Z,1674683693.387 [Waterlinked](INFO): Starting
2023-01-25T21:54:53.779Z,1674683693.779 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-01-25T21:54:53.779Z,1674683693.779 [AHRS_M2] Data Fault, FailCount= 1
2023-01-25T21:54:53.779Z,1674683693.779 [AHRS_M2](ERROR): Data Fault
2023-01-25T21:54:53.789Z,1674683693.789 [Waterlinked](INFO): Starting
2023-01-25T21:54:54.172Z,1674683694.172 [AHRS_M2](INFO): Powering down
2023-01-25T21:54:54.257Z,1674683694.257 [Waterlinked](INFO): Starting
2023-01-25T21:54:54.578Z,1674683694.578 [Waterlinked](INFO): Starting
2023-01-25T21:54:54.989Z,1674683694.989 [Waterlinked](INFO): Starting
2023-01-25T21:54:55.406Z,1674683695.406 [Waterlinked](INFO): Starting
2023-01-25T21:54:55.790Z,1674683695.790 [Waterlinked](INFO): Starting
2023-01-25T21:54:56.198Z,1674683696.198 [Waterlinked](INFO): Starting
2023-01-25T21:54:56.598Z,1674683696.598 [Waterlinked](INFO): Starting
2023-01-25T21:54:57.005Z,1674683697.005 [Waterlinked](INFO): Starting
2023-01-25T21:54:57.409Z,1674683697.409 [Waterlinked](INFO): Starting
2023-01-25T21:54:57.812Z,1674683697.812 [Waterlinked](INFO): Starting
2023-01-25T21:54:58.214Z,1674683698.214 [Waterlinked](INFO): Starting
2023-01-25T21:54:58.625Z,1674683698.625 [Waterlinked](INFO): Starting
2023-01-25T21:54:59.022Z,1674683699.022 [Waterlinked](INFO): Starting
2023-01-25T21:54:59.433Z,1674683699.433 [Waterlinked](INFO): Starting
2023-01-25T21:54:59.833Z,1674683699.833 [Waterlinked](INFO): Starting
2023-01-25T21:55:00.234Z,1674683700.234 [Waterlinked](INFO): Starting
2023-01-25T21:55:00.638Z,1674683700.638 [Waterlinked](INFO): Starting
2023-01-25T21:55:01.049Z,1674683701.049 [Waterlinked](INFO): Starting
2023-01-25T21:55:01.446Z,1674683701.446 [Waterlinked](INFO): Starting
2023-01-25T21:55:01.850Z,1674683701.850 [Waterlinked](INFO): Starting
2023-01-25T21:55:02.261Z,1674683702.261 [Waterlinked](INFO): Starting
2023-01-25T21:55:02.658Z,1674683702.658 [Waterlinked](INFO): Starting
2023-01-25T21:55:03.069Z,1674683703.069 [Waterlinked](INFO): Starting
2023-01-25T21:55:03.469Z,1674683703.469 [Waterlinked](INFO): Starting
2023-01-25T21:55:03.870Z,1674683703.870 [NAL9602](INFO): Powering up NAL9602
2023-01-25T21:55:03.872Z,1674683703.872 [Waterlinked](INFO): Starting
2023-01-25T21:55:03.872Z,1674683703.872 [Waterlinked](FAULT): Could not initialize.
2023-01-25T21:55:03.872Z,1674683703.872 [Waterlinked] Communications Fault, FailCount= 1
2023-01-25T21:55:03.872Z,1674683703.872 [Waterlinked](ERROR): Communications Fault
2023-01-25T21:55:03.883Z,1674683703.883 [SBIT](IMPORTANT): Beginning Startup BIT
2023-01-25T21:55:04.277Z,1674683704.277 [Waterlinked](INFO): Stop
2023-01-25T21:55:04.343Z,1674683704.343 [Waterlinked](INFO): Powering down
2023-01-25T21:55:04.685Z,1674683704.685 [Waterlinked](INFO): Stopping
2023-01-25T21:55:04.685Z,1674683704.685 [Waterlinked](ERROR): LCB error: Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T21:55:05.090Z,1674683705.090 [Waterlinked](INFO): Stopped
2023-01-25T21:55:06.475Z,1674683706.475 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-01-25T21:55:17.226Z,1674683717.226 [SBIT](ERROR): Could not read elevatorAngleReader_.
2023-01-25T21:55:17.226Z,1674683717.226 [SBIT](ERROR): Could not read rudderAngleReader_.
2023-01-25T21:55:17.226Z,1674683717.226 [SBIT](ERROR): Could not read massPosReader_.
2023-01-25T21:55:24.480Z,1674683724.480 [NAL9602](ERROR): NAL9602 initialization error.
2023-01-25T21:55:24.481Z,1674683724.481 [NAL9602] Communications Fault, FailCount= 1
2023-01-25T21:55:24.481Z,1674683724.481 [NAL9602](ERROR): Communications Fault
2023-01-25T21:55:24.880Z,1674683724.880 [NAL9602](INFO): Powering down
2023-01-25T21:55:35.635Z,1674683735.635 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2023-01-25T21:55:35.635Z,1674683735.635 [DAT] Communications Fault, FailCount= 1
2023-01-25T21:55:35.635Z,1674683735.635 [DAT](ERROR): Communications Fault
2023-01-25T21:55:36.039Z,1674683736.039 [DAT](INFO): Powering down
2023-01-25T21:55:36.511Z,1674683736.511 [DAT](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-01-25T21:55:36.511Z,1674683736.511 [DAT] Hardware Fault, FailCount= 1
2023-01-25T21:55:36.511Z,1674683736.511 [DAT](ERROR): Hardware Fault
2023-01-25T21:55:43.900Z,1674683743.900 [SBIT](ERROR): Could not read elevatorAngleReader_.
2023-01-25T21:55:43.900Z,1674683743.900 [SBIT](ERROR): Could not read rudderAngleReader_.
2023-01-25T21:55:43.900Z,1674683743.900 [SBIT](ERROR): Could not read massPosReader_.
2023-01-25T21:55:43.900Z,1674683743.900 [SBIT](FAULT): Control surface position failure.
2023-01-25T21:55:57.643Z,1674683757.643 [SBIT](ERROR): Could not read elevatorAngleReader_.
2023-01-25T21:55:57.643Z,1674683757.643 [SBIT](ERROR): Could not read rudderAngleReader_.
2023-01-25T21:55:57.643Z,1674683757.643 [SBIT](ERROR): Could not read massPosReader_.
2023-01-25T21:55:57.643Z,1674683757.643 [SBIT](FAULT): Control surface position failure.
2023-01-25T21:55:58.029Z,1674683758.029 [SBIT](CRITICAL): SBIT FAILED
2023-01-25T21:55:58.029Z,1674683758.029 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-01-25T21:55:58.422Z,1674683758.422 [MissionManager](IMPORTANT): Started mission Startup
2023-01-25T21:55:58.423Z,1674683758.423 [Startup] Running Loop=1
2023-01-25T21:55:58.423Z,1674683758.423 [Startup](DEBUG): Aggregate::initialize Startup
2023-01-25T21:55:58.423Z,1674683758.423 [Startup:A.GoToSurface] Running Loop=1
2023-01-25T21:55:58.423Z,1674683758.423 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-01-25T21:55:58.424Z,1674683758.424 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-01-25T21:55:58.424Z,1674683758.424 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-01-25T21:55:58.424Z,1674683758.424 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-01-25T21:55:58.425Z,1674683758.425 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-01-25T21:55:58.425Z,1674683758.425 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-01-25T21:55:58.427Z,1674683758.427 [Startup:StartupSatComms] Running Loop=1
2023-01-25T21:55:58.427Z,1674683758.427 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-01-25T21:55:58.427Z,1674683758.427 [Startup:StartupSatComms:A] Running Loop=1
2023-01-25T21:55:58.832Z,1674683758.832 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-01-25T21:56:35.589Z,1674683795.589 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-01-25T21:56:35.589Z,1674683795.589 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-01-25T21:56:35.589Z,1674683795.589 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-25T21:56:35.591Z,1674683795.591 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-01-25T21:56:35.591Z,1674683795.591 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-01-25T21:56:35.591Z,1674683795.591 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-01-25T21:56:58.617Z,1674683818.617 [Startup:StartupSatComms:A](INFO): Timed out from 2023-01-25T21:55:58.4Z
2023-01-25T21:56:58.617Z,1674683818.617 [Startup:StartupSatComms:A] Stopped
2023-01-25T21:56:58.617Z,1674683818.617 [Startup:StartupSatComms:B] Running Loop=1
2023-01-25T21:56:59.020Z,1674683819.020 [Startup:StartupSatComms:B](DEBUG): Initialize R