2022-05-06T18:52:43.174Z,1651863163.174 [Supervisor](DEBUG): Initializing supervisor.
2022-05-06T18:52:43.179Z,1651863163.179 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-05-06T18:52:43.179Z,1651863163.179 [SyncHandler](INFO): Protected caller Thread ID is 5390
2022-05-06T18:52:43.180Z,1651863163.180 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-05-06T18:52:43.181Z,1651863163.181 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-05-06T18:52:43.181Z,1651863163.181 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5391
2022-05-06T18:52:43.185Z,1651863163.185 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-05-06T18:52:43.204Z,1651863163.204 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-05-06T18:52:43.205Z,1651863163.205 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-05-06T18:52:43.205Z,1651863163.205 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5392
2022-05-06T18:52:43.210Z,1651863163.210 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-05-06T18:52:43.211Z,1651863163.211 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-05-06T18:52:43.211Z,1651863163.211 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5393
2022-05-06T18:52:43.213Z,1651863163.213 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-05-06T18:52:43.214Z,1651863163.214 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-05-06T18:52:43.215Z,1651863163.215 [logger ThreadHandler](INFO): Protected caller Thread ID is 5394
2022-05-06T18:52:43.218Z,1651863163.218 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-05-06T18:52:43.219Z,1651863163.219 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-05-06T18:52:43.221Z,1651863163.221 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-05-06T18:52:43.314Z,1651863163.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-05-06T18:52:43.315Z,1651863163.315 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-05-06T18:52:43.533Z,1651863163.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-05-06T18:52:43.533Z,1651863163.533 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-05-06T18:52:43.792Z,1651863163.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-05-06T18:52:43.793Z,1651863163.793 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-05-06T18:52:43.899Z,1651863163.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-05-06T18:52:43.900Z,1651863163.900 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-05-06T18:52:44.630Z,1651863164.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-05-06T18:52:44.631Z,1651863164.631 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-05-06T18:52:45.086Z,1651863165.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-05-06T18:52:45.087Z,1651863165.087 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-05-06T18:52:45.656Z,1651863165.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-05-06T18:52:45.657Z,1651863165.657 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-05-06T18:52:46.042Z,1651863166.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-05-06T18:52:46.043Z,1651863166.043 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-05-06T18:52:46.375Z,1651863166.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-05-06T18:52:46.376Z,1651863166.376 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-05-06T18:52:46.750Z,1651863166.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-05-06T18:52:46.750Z,1651863166.750 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-05-06T18:52:46.970Z,1651863166.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-05-06T18:52:46.971Z,1651863166.971 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-05-06T18:52:47.100Z,1651863167.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-05-06T18:52:47.101Z,1651863167.101 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-05-06T18:52:47.181Z,1651863167.181 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-05-06T18:52:47.258Z,1651863167.258 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-05-06T18:52:47.449Z,1651863167.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-05-06T18:52:47.449Z,1651863167.449 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-05-06T18:52:47.667Z,1651863167.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-05-06T18:52:47.670Z,1651863167.670 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2022-05-06T18:52:47.670Z,1651863167.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2022-05-06T18:52:47.752Z,1651863167.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2022-05-06T18:52:48.026Z,1651863168.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-05-06T18:52:48.027Z,1651863168.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2022-05-06T18:52:48.192Z,1651863168.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2022-05-06T18:52:48.594Z,1651863168.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2022-05-06T18:52:48.786Z,1651863168.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2022-05-06T18:52:48.871Z,1651863168.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2022-05-06T18:52:48.977Z,1651863168.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2022-05-06T18:52:49.078Z,1651863169.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2022-05-06T18:52:49.242Z,1651863169.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2022-05-06T18:52:49.346Z,1651863169.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2022-05-06T18:52:49.446Z,1651863169.446 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2022-05-06T18:52:49.446Z,1651863169.446 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-05-06T18:52:49.461Z,1651863169.461 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-05-06T18:52:49.571Z,1651863169.571 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-05-06T18:52:49.572Z,1651863169.572 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-05-06T18:52:49.699Z,1651863169.699 [BuoyancyServo] Loaded
2022-05-06T18:52:49.699Z,1651863169.699 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-05-06T18:52:49.720Z,1651863169.720 [ElevatorServo] Loaded
2022-05-06T18:52:49.720Z,1651863169.720 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-05-06T18:52:49.740Z,1651863169.740 [MassServo] Loaded
2022-05-06T18:52:49.740Z,1651863169.740 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-05-06T18:52:49.760Z,1651863169.760 [RudderServo] Loaded
2022-05-06T18:52:49.760Z,1651863169.760 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-05-06T18:52:49.776Z,1651863169.776 [ThrusterHE] Loaded
2022-05-06T18:52:49.777Z,1651863169.777 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-05-06T18:52:49.777Z,1651863169.777 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-05-06T18:52:49.778Z,1651863169.778 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-05-06T18:52:49.918Z,1651863169.918 [SBIT](DEBUG): Construct Startup Built In Test.
2022-05-06T18:52:49.933Z,1651863169.933 [SBIT] Loaded
2022-05-06T18:52:49.934Z,1651863169.934 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-05-06T18:52:49.936Z,1651863169.936 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-05-06T18:52:49.958Z,1651863169.958 [IBIT] Loaded
2022-05-06T18:52:49.958Z,1651863169.958 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-05-06T18:52:49.963Z,1651863169.963 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-05-06T18:52:50.182Z,1651863170.182 [CBIT] Loaded
2022-05-06T18:52:50.182Z,1651863170.182 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-05-06T18:52:50.182Z,1651863170.182 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-05-06T18:52:50.183Z,1651863170.183 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-05-06T18:52:50.326Z,1651863170.326 [ESPComponent] Loaded
2022-05-06T18:52:50.326Z,1651863170.326 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-05-06T18:52:50.345Z,1651863170.345 [PAR_Licor] Loaded
2022-05-06T18:52:50.346Z,1651863170.346 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-05-06T18:52:50.378Z,1651863170.378 [WetLabsBB2FL] Loaded
2022-05-06T18:52:50.378Z,1651863170.378 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-05-06T18:52:50.379Z,1651863170.379 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-05-06T18:52:50.379Z,1651863170.379 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5477
2022-05-06T18:52:50.380Z,1651863170.380 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-05-06T18:52:50.381Z,1651863170.381 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-05-06T18:52:50.431Z,1651863170.431 [DepthRateCalculator] Loaded
2022-05-06T18:52:50.431Z,1651863170.431 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-05-06T18:52:50.436Z,1651863170.436 [PitchRateCalculator] Loaded
2022-05-06T18:52:50.436Z,1651863170.436 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-05-06T18:52:50.447Z,1651863170.447 [SpeedCalculator] Loaded
2022-05-06T18:52:50.447Z,1651863170.447 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-05-06T18:52:50.452Z,1651863170.452 [YawRateCalculator] Loaded
2022-05-06T18:52:50.452Z,1651863170.452 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-05-06T18:52:50.471Z,1651863170.471 [ElevatorOffsetCalculator] Loaded
2022-05-06T18:52:50.471Z,1651863170.471 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-05-06T18:52:50.488Z,1651863170.488 [ShortestDistanceToPolygonSidesCalculator] Loaded
2022-05-06T18:52:50.488Z,1651863170.488 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2022-05-06T18:52:50.488Z,1651863170.488 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-05-06T18:52:50.489Z,1651863170.489 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-05-06T18:52:50.586Z,1651863170.586 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-05-06T18:52:50.587Z,1651863170.587 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-05-06T18:52:50.614Z,1651863170.614 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-05-06T18:52:50.614Z,1651863170.614 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-05-06T18:52:50.726Z,1651863170.726 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-05-06T18:52:50.726Z,1651863170.726 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-05-06T18:52:50.739Z,1651863170.739 [NavChart] Loaded
2022-05-06T18:52:50.740Z,1651863170.740 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-05-06T18:52:50.745Z,1651863170.745 [UniversalFixResidualReporter] Loaded
2022-05-06T18:52:50.746Z,1651863170.746 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-05-06T18:52:50.746Z,1651863170.746 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-05-06T18:52:50.747Z,1651863170.747 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-05-06T18:52:51.146Z,1651863171.146 [AHRS_M2] Loaded
2022-05-06T18:52:51.146Z,1651863171.146 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-05-06T18:52:51.242Z,1651863171.242 [DataOverHttps] Loaded
2022-05-06T18:52:51.242Z,1651863171.242 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-05-06T18:52:51.243Z,1651863171.243 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2022-05-06T18:52:51.244Z,1651863171.244 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5478
2022-05-06T18:52:51.264Z,1651863171.264 [Depth_Keller] Loaded
2022-05-06T18:52:51.264Z,1651863171.264 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-05-06T18:52:51.302Z,1651863171.302 [DVL_micro] Loaded
2022-05-06T18:52:51.302Z,1651863171.302 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2022-05-06T18:52:51.360Z,1651863171.360 [NAL9602] Loaded
2022-05-06T18:52:51.360Z,1651863171.360 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-05-06T18:52:51.386Z,1651863171.386 [Onboard] Loaded
2022-05-06T18:52:51.386Z,1651863171.386 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-05-06T18:52:51.387Z,1651863171.387 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0
2022-05-06T18:52:51.388Z,1651863171.388 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5479
2022-05-06T18:52:51.400Z,1651863171.400 [Power24vConverter] Loaded
2022-05-06T18:52:51.400Z,1651863171.400 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-05-06T18:52:51.413Z,1651863171.413 [Radio_Surface] Loaded
2022-05-06T18:52:51.413Z,1651863171.413 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-05-06T18:52:51.414Z,1651863171.414 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A3D4E0
2022-05-06T18:52:51.415Z,1651863171.415 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5480
2022-05-06T18:52:51.416Z,1651863171.416 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-05-06T18:52:51.416Z,1651863171.416 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-05-06T18:52:51.476Z,1651863171.476 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-05-06T18:52:51.476Z,1651863171.476 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-05-06T18:52:51.527Z,1651863171.527 [VerticalControl](DEBUG): Construct VerticalControl.
2022-05-06T18:52:51.579Z,1651863171.579 [VerticalControl] Loaded
2022-05-06T18:52:51.579Z,1651863171.579 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-05-06T18:52:51.582Z,1651863171.582 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-05-06T18:52:51.618Z,1651863171.618 [HorizontalControl] Loaded
2022-05-06T18:52:51.618Z,1651863171.618 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-05-06T18:52:51.620Z,1651863171.620 [SpeedControl](DEBUG): Construct SpeedControl.
2022-05-06T18:52:51.622Z,1651863171.622 [SpeedControl] Loaded
2022-05-06T18:52:51.622Z,1651863171.622 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-05-06T18:52:51.625Z,1651863171.625 [LoopControl](DEBUG): Construct LoopControl.
2022-05-06T18:52:51.626Z,1651863171.626 [LoopControl] Loaded
2022-05-06T18:52:51.626Z,1651863171.626 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-05-06T18:52:51.626Z,1651863171.626 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-05-06T18:52:51.627Z,1651863171.627 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-05-06T18:52:51.637Z,1651863171.637 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-05-06T18:52:51.638Z,1651863171.638 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-05-06T18:52:51.949Z,1651863171.949 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-05-06T18:52:51.956Z,1651863171.956 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-05-06T18:52:51.958Z,1651863171.958 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-05-06T18:52:51.969Z,1651863171.969 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-05-06T18:52:51.970Z,1651863171.970 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0
2022-05-06T18:52:51.971Z,1651863171.971 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5481
2022-05-06T18:52:51.975Z,1651863171.975 [Supervisor](INFO): Main Thread ID is 5389
2022-05-06T18:52:51.976Z,1651863171.976 [Supervisor](DEBUG): Running supervisor.
2022-05-06T18:52:51.976Z,1651863171.976 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5482
2022-05-06T18:52:51.977Z,1651863171.977 [CommandExec](INFO): Initializing the command executive.
2022-05-06T18:52:51.978Z,1651863171.978 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5483
2022-05-06T18:52:51.980Z,1651863171.980 [controlThread ThreadHandler](INFO): Handler Thread ID is 5484
2022-05-06T18:52:51.981Z,1651863171.981 [controlThread](DEBUG): Initializing ControlThread
2022-05-06T18:52:51.983Z,1651863171.983 [SBIT](INFO): Initialize SBIT Component.
2022-05-06T18:52:51.983Z,1651863171.983 [SBIT](IMPORTANT): git: 2022-04-25-37-g2a944135f
2022-05-06T18:52:51.983Z,1651863171.983 [SBIT](INFO): git hash: 2a944135f4713de004d013a6b17eee164aec7a5a
2022-05-06T18:52:51.984Z,1651863171.984 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-05-06T18:52:51.985Z,1651863171.985 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-05-06T18:52:51.986Z,1651863171.986 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2022-05-06T18:52:51.987Z,1651863171.987 [IBIT](INFO): Initialize IBIT Component.
2022-05-06T18:52:51.987Z,1651863171.987 [CBIT](DEBUG): Initialize CBIT Component.
2022-05-06T18:52:51.988Z,1651863171.988 [logger ThreadHandler](INFO): Handler Thread ID is 5485
2022-05-06T18:52:51.000Z,1651863172.000 [CBIT](DEBUG): Initialized mux pins.
2022-05-06T18:52:51.000Z,1651863172.000 [CBIT](DEBUG): Initializing the watchdog timer.
2022-05-06T18:52:52.008Z,1651863172.008 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5486
2022-05-06T18:52:52.013Z,1651863172.013 [WetLabsBB2FL](INFO): Powering up
2022-05-06T18:52:52.014Z,1651863172.014 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5488
2022-05-06T18:52:52.015Z,1651863172.015 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-05-06T18:52:52.024Z,1651863172.024 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-05-06T18:52:52.024Z,1651863172.024 [CBIT](DEBUG): Initializing heartbeat.
2022-05-06T18:52:52.025Z,1651863172.025 [Onboard ThreadHandler](INFO): Handler Thread ID is 5489
2022-05-06T18:52:52.046Z,1651863172.046 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5490
2022-05-06T18:52:52.072Z,1651863172.072 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5491
2022-05-06T18:52:52.075Z,1651863172.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2022-05-06T18:52:52.075Z,1651863172.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2022-05-06T18:52:52.075Z,1651863172.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2022-05-06T18:52:52.075Z,1651863172.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2022-05-06T18:52:52.076Z,1651863172.076 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000
2022-05-06T18:52:52.076Z,1651863172.076 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000
2022-05-06T18:52:52.076Z,1651863172.076 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000
2022-05-06T18:52:52.076Z,1651863172.076 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000
2022-05-06T18:52:52.077Z,1651863172.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000
2022-05-06T18:52:52.077Z,1651863172.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000
2022-05-06T18:52:52.077Z,1651863172.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000
2022-05-06T18:52:52.077Z,1651863172.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000
2022-05-06T18:52:52.096Z,1651863172.096 [CBIT](DEBUG): Deactivating GF circuits.
2022-05-06T18:52:52.096Z,1651863172.096 [CBIT](DEBUG): Deactivating emergency mode.
2022-05-06T18:52:52.132Z,1651863172.132 [CBIT](DEBUG): Backplane powered.
2022-05-06T18:52:52.136Z,1651863172.136 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-05-06T18:52:52.137Z,1651863172.137 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-05-06T18:52:52.137Z,1651863172.137 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-05-06T18:52:52.137Z,1651863172.137 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-05-06T18:52:52.138Z,1651863172.138 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-05-06T18:52:52.138Z,1651863172.138 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2022-05-06T18:52:52.140Z,1651863172.140 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-05-06T18:52:52.140Z,1651863172.140 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-05-06T18:52:52.149Z,1651863172.149 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-05-06T18:52:52.151Z,1651863172.151 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-05-06T18:52:52.151Z,1651863172.151 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-05-06T18:52:52.152Z,1651863172.152 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-05-06T18:52:52.153Z,1651863172.153 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-05-06T18:52:52.190Z,1651863172.190 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-06T18:52:52.229Z,1651863172.229 [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>


2022-05-06T18:52:52.230Z,1651863172.230 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-05-06T18:52:52.324Z,1651863172.324 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-05-06T18:52:52.326Z,1651863172.326 [Default:A.Wait](DEBUG): Construct Wait.
2022-05-06T18:52:52.328Z,1651863172.328 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-06T18:52:52.351Z,1651863172.351 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-05-06T18:52:52.386Z,1651863172.386 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-05-06T18:52:52.407Z,1651863172.407 [Default:E.Execute](DEBUG): Construct Execute.
2022-05-06T18:52:52.411Z,1651863172.411 [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="P2H">
                <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>


2022-05-06T18:52:52.428Z,1651863172.428 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-05-06T18:52:52.452Z,1651863172.452 [Radio_Surface](INFO): Powering up
2022-05-06T18:52:52.457Z,1651863172.457 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-06T18:52:52.498Z,1651863172.498 [Depth_Keller](ERROR): Pressure reading out of range: 1896.040405 decibar
2022-05-06T18:52:52.505Z,1651863172.505 [Power24vConverter](INFO): Powering up.
2022-05-06T18:52:52.523Z,1651863172.523 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-05-06T18:52:52.554Z,1651863172.554 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:52.561Z,1651863172.561 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-05-06T18:52:52.562Z,1651863172.562 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:52.569Z,1651863172.569 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-06T18:52:52.570Z,1651863172.570 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:52.577Z,1651863172.577 [MassServo](DEBUG): Initializing MassServo.
2022-05-06T18:52:52.578Z,1651863172.578 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:52.592Z,1651863172.592 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-06T18:52:52.593Z,1651863172.593 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:52.601Z,1651863172.601 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-05-06T18:52:52.603Z,1651863172.603 [CBIT](FAULT): Main Battery Failure. Count: 1
2022-05-06T18:52:53.584Z,1651863173.584 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-05-06T18:52:53.584Z,1651863173.584 [RudderServo](FAULT): Rudder failed to initialize
2022-05-06T18:52:53.584Z,1651863173.584 [RudderServo] Communications Fault, FailCount= 1
2022-05-06T18:52:53.584Z,1651863173.584 [RudderServo](ERROR): Communications Fault
2022-05-06T18:52:53.588Z,1651863173.588 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-05-06T18:52:54.064Z,1651863174.064 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2022-05-06T18:52:54.068Z,1651863174.068 [ElevatorServo](FAULT): Elevator failed to initialize
2022-05-06T18:52:54.068Z,1651863174.068 [ElevatorServo] Communications Fault, FailCount= 1
2022-05-06T18:52:54.068Z,1651863174.068 [ElevatorServo](ERROR): Communications Fault
2022-05-06T18:52:54.070Z,1651863174.070 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-06T18:52:54.070Z,1651863174.070 [RudderServo](INFO): Powering down
2022-05-06T18:52:54.077Z,1651863174.077 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2022-05-06T18:52:54.200Z,1651863174.200 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-06T18:52:54.208Z,1651863174.208 [ElevatorServo](INFO): Powering down
2022-05-06T18:52:54.516Z,1651863174.516 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:54.637Z,1651863174.637 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-06T18:52:54.643Z,1651863174.643 [CBIT](INFO): Clearing failed state for component RudderServo
2022-05-06T18:52:54.643Z,1651863174.643 [RudderServo] No Fault, FailCount= 1
2022-05-06T18:52:54.920Z,1651863174.920 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:55.041Z,1651863175.041 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-06T18:52:55.045Z,1651863175.045 [CBIT](INFO): Clearing failed state for component ElevatorServo
2022-05-06T18:52:55.045Z,1651863175.045 [ElevatorServo] No Fault, FailCount= 1
2022-05-06T18:52:55.660Z,1651863175.660 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-05-06T18:52:55.660Z,1651863175.660 [RudderServo](FAULT): Rudder failed to initialize
2022-05-06T18:52:55.660Z,1651863175.660 [RudderServo] Communications Fault, FailCount= 2
2022-05-06T18:52:55.660Z,1651863175.660 [RudderServo](ERROR): Communications Fault
2022-05-06T18:52:55.664Z,1651863175.664 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-05-06T18:52:56.032Z,1651863176.032 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-06T18:52:56.384Z,1651863176.384 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2022-05-06T18:52:56.384Z,1651863176.384 [ElevatorServo](FAULT): Elevator failed to initialize
2022-05-06T18:52:56.384Z,1651863176.384 [ElevatorServo] Communications Fault, FailCount= 2
2022-05-06T18:52:56.384Z,1651863176.384 [ElevatorServo](ERROR): Communications Fault
2022-05-06T18:52:56.432Z,1651863176.432 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-06T18:52:56.433Z,1651863176.433 [RudderServo](INFO): Powering down
2022-05-06T18:52:56.437Z,1651863176.437 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2022-05-06T18:52:56.772Z,1651863176.772 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-05-06T18:52:56.866Z,1651863176.866 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-06T18:52:56.866Z,1651863176.866 [ElevatorServo](INFO): Powering down
2022-05-06T18:52:56.989Z,1651863176.989 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:57.109Z,1651863177.109 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-06T18:52:57.113Z,1651863177.113 [CBIT](FAULT): Main Battery Failure. Count: 11
2022-05-06T18:52:57.115Z,1651863177.115 [CBIT](INFO): Clearing failed state for component RudderServo
2022-05-06T18:52:57.115Z,1651863177.115 [RudderServo] No Fault, FailCount= 2
2022-05-06T18:52:57.300Z,1651863177.300 [WetLabsBB2FL] Communications Fault, FailCount= 1
2022-05-06T18:52:57.301Z,1651863177.301 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-06T18:52:57.351Z,1651863177.351 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:57.469Z,1651863177.469 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-06T18:52:57.511Z,1651863177.511 [CBIT](INFO): Clearing failed state for component ElevatorServo
2022-05-06T18:52:57.511Z,1651863177.511 [ElevatorServo] No Fault, FailCount= 2
2022-05-06T18:52:57.511Z,1651863177.511 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-06T18:52:57.804Z,1651863177.804 [WetLabsBB2FL](INFO): Powering down
2022-05-06T18:52:58.068Z,1651863178.068 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-05-06T18:52:58.068Z,1651863178.068 [RudderServo](FAULT): Rudder failed to initialize
2022-05-06T18:52:58.068Z,1651863178.068 [RudderServo] Communications Fault, FailCount= 3
2022-05-06T18:52:58.068Z,1651863178.068 [RudderServo](ERROR): Communications Fault
2022-05-06T18:52:58.069Z,1651863178.069 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-05-06T18:52:58.131Z,1651863178.131 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-05-06T18:52:58.132Z,1651863178.132 [CBIT](CRITICAL): Communications Fault in component: RudderServo
2022-05-06T18:52:58.269Z,1651863178.269 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-06T18:52:58.269Z,1651863178.269 [RudderServo](INFO): Powering down
2022-05-06T18:52:58.390Z,1651863178.390 [CommandExec](FAULT): Scheduling is paused
2022-05-06T18:52:58.390Z,1651863178.390 [CBIT](INFO): Critical error at 20220506T185258
2022-05-06T18:52:58.390Z,1651863178.390 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-05-06T18:52:59.056Z,1651863179.056 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2022-05-06T18:52:59.056Z,1651863179.056 [ElevatorServo](FAULT): Elevator failed to initialize
2022-05-06T18:52:59.056Z,1651863179.056 [ElevatorServo] Communications Fault, FailCount= 3
2022-05-06T18:52:59.056Z,1651863179.056 [ElevatorServo](ERROR): Communications Fault
2022-05-06T18:52:59.084Z,1651863179.084 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2022-05-06T18:52:59.085Z,1651863179.085 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo
2022-05-06T18:52:59.085Z,1651863179.085 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-05-06T18:52:59.085Z,1651863179.085 [WetLabsBB2FL] No Fault, FailCount= 1
2022-05-06T18:52:59.412Z,1651863179.412 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:52:59.425Z,1651863179.425 [WetLabsBB2FL](INFO): Powering up
2022-05-06T18:52:59.460Z,1651863179.460 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-06T18:52:59.460Z,1651863179.460 [ElevatorServo](INFO): Powering down
2022-05-06T18:52:59.489Z,1651863179.489 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:52:59.609Z,1651863179.609 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-06T18:52:59.611Z,1651863179.611 [CBIT](INFO): Critical error at 20220506T185259
2022-05-06T18:52:59.956Z,1651863179.956 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:00.380Z,1651863180.380 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:00.418Z,1651863180.418 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-06T18:53:00.537Z,1651863180.537 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-06T18:53:00.900Z,1651863180.900 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:01.308Z,1651863181.308 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:01.736Z,1651863181.736 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:01.798Z,1651863181.798 [CBIT](FAULT): Main Battery Failure. Count: 21
2022-05-06T18:53:02.168Z,1651863182.168 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:02.596Z,1651863182.596 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:03.036Z,1651863183.036 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:03.456Z,1651863183.456 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:03.884Z,1651863183.884 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:04.312Z,1651863184.312 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:04.720Z,1651863184.720 [WetLabsBB2FL] Communications Fault, FailCount= 2
2022-05-06T18:53:04.720Z,1651863184.720 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-06T18:53:04.752Z,1651863184.752 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:04.824Z,1651863184.824 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-06T18:53:05.160Z,1651863185.160 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:05.220Z,1651863185.220 [WetLabsBB2FL](INFO): Powering down
2022-05-06T18:53:05.580Z,1651863185.580 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:05.580Z,1651863185.580 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-05-06T18:53:05.580Z,1651863185.580 [AHRS_M2] Communications Fault, FailCount= 1
2022-05-06T18:53:05.580Z,1651863185.580 [AHRS_M2](ERROR): Communications Fault
2022-05-06T18:53:05.652Z,1651863185.652 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-05-06T18:53:05.683Z,1651863185.683 [AHRS_M2](INFO): Powering down
2022-05-06T18:53:05.840Z,1651863185.840 [CBIT](FAULT): Main Battery Failure. Count: 31
2022-05-06T18:53:06.125Z,1651863186.125 [CommandExec](IMPORTANT): got command quit
2022-05-06T18:53:06.136Z,1651863186.136 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-05-06T18:53:06.136Z,1651863186.136 [WetLabsBB2FL] No Fault, FailCount= 2
2022-05-06T18:53:06.433Z,1651863186.433 [WetLabsBB2FL](INFO): Powering up
2022-05-06T18:53:06.564Z,1651863186.564 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-05-06T18:53:06.564Z,1651863186.564 [AHRS_M2] No Fault, FailCount= 1
2022-05-06T18:53:06.872Z,1651863186.872 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-06T18:53:07.128Z,1651863187.128 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:07.128Z,1651863187.128 [CommandExec](INFO): Uninitializing the command executive.
2022-05-06T18:53:07.129Z,1651863187.129 [CommandExec](INFO): Uninitializing the command scheduler.
2022-05-06T18:53:07.129Z,1651863187.129 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:07.216Z,1651863187.216 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-05-06T18:53:07.216Z,1651863187.216 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-05-06T18:53:07.216Z,1651863187.216 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:07.217Z,1651863187.217 [NavChartDb](INFO): Join timeout helper Thread ID is 5508
2022-05-06T18:53:09.765Z,1651863189.765 [CBIT](FAULT): Main Battery Failure. Count: 41
2022-05-06T18:53:10.428Z,1651863190.428 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:10.440Z,1651863190.440 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-05-06T18:53:10.440Z,1651863190.440 [DVL_micro] Communications Fault, FailCount= 1
2022-05-06T18:53:10.440Z,1651863190.440 [DVL_micro](ERROR): Communications Fault
2022-05-06T18:53:10.528Z,1651863190.528 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-05-06T18:53:10.848Z,1651863190.848 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:10.924Z,1651863190.924 [DVL_micro](INFO): Powering down
2022-05-06T18:53:11.169Z,1651863191.169 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:11.170Z,1651863191.170 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:11.184Z,1651863191.184 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-05-06T18:53:11.184Z,1651863191.184 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:11.184Z,1651863191.184 [Radio_Surface](INFO): Join timeout helper Thread ID is 5509
2022-05-06T18:53:11.340Z,1651863191.340 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:11.441Z,1651863191.441 [Radio_Surface](INFO): Powering down
2022-05-06T18:53:11.444Z,1651863191.444 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:11.444Z,1651863191.444 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:11.456Z,1651863191.456 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-05-06T18:53:11.456Z,1651863191.456 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:11.456Z,1651863191.456 [Onboard](INFO): Join timeout helper Thread ID is 5510
2022-05-06T18:53:11.748Z,1651863191.748 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:11.748Z,1651863191.748 [WetLabsBB2FL] Communications Fault, FailCount= 3
2022-05-06T18:53:11.748Z,1651863191.748 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-06T18:53:11.795Z,1651863191.795 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-05-06T18:53:11.795Z,1651863191.795 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-05-06T18:53:11.795Z,1651863191.795 [DVL_micro] No Fault, FailCount= 1
2022-05-06T18:53:12.040Z,1651863192.040 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:12.040Z,1651863192.040 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.056Z,1651863192.056 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-05-06T18:53:12.056Z,1651863192.056 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.056Z,1651863192.056 [DataOverHttps](INFO): Join timeout helper Thread ID is 5511
2022-05-06T18:53:12.092Z,1651863192.092 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:12.093Z,1651863192.093 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.112Z,1651863192.112 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-05-06T18:53:12.112Z,1651863192.112 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.113Z,1651863192.113 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5512
2022-05-06T18:53:12.164Z,1651863192.164 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:12.248Z,1651863192.248 [WetLabsBB2FL](INFO): Powering down
2022-05-06T18:53:12.250Z,1651863192.250 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:12.250Z,1651863192.250 [WetLabsBB2FL](INFO): Powering down
2022-05-06T18:53:12.251Z,1651863192.251 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.261Z,1651863192.261 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-05-06T18:53:12.261Z,1651863192.261 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.262Z,1651863192.262 [logger](INFO): Join timeout helper Thread ID is 5514
2022-05-06T18:53:12.302Z,1651863192.302 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:12.303Z,1651863192.303 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.313Z,1651863192.313 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-05-06T18:53:12.313Z,1651863192.313 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.313Z,1651863192.313 [CommandLine](INFO): Join timeout helper Thread ID is 5515
2022-05-06T18:53:12.396Z,1651863192.396 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:12.397Z,1651863192.397 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.413Z,1651863192.413 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-05-06T18:53:12.413Z,1651863192.413 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.413Z,1651863192.413 [CommandExec](INFO): Join timeout helper Thread ID is 5516
2022-05-06T18:53:12.414Z,1651863192.414 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-05-06T18:53:12.414Z,1651863192.414 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:12.415Z,1651863192.415 [controlThread](INFO): Join timeout helper Thread ID is 5517
2022-05-06T18:53:12.600Z,1651863192.600 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-06T18:53:12.637Z,1651863192.637 [SBIT](IMPORTANT): Beginning Startup BIT
2022-05-06T18:53:12.641Z,1651863192.641 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-06T18:53:12.643Z,1651863192.643 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-06T18:53:12.643Z,1651863192.643 [controlThread](DEBUG): Uninitializing ControlThread
2022-05-06T18:53:12.645Z,1651863192.645 [AHRS_M2](INFO): Powering down
2022-05-06T18:53:12.788Z,1651863192.788 [DVL_micro](INFO): Powering down
2022-05-06T18:53:12.789Z,1651863192.789 [NAL9602](INFO): Powering down
2022-05-06T18:53:12.790Z,1651863192.790 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-05-06T18:53:12.791Z,1651863192.791 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-05-06T18:53:12.792Z,1651863192.792 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-05-06T18:53:12.793Z,1651863192.793 [MissionManager](INFO): Uninitializing Mission Default
2022-05-06T18:53:12.795Z,1651863192.795 [MissionManager](INFO): Uninitializing Mission Startup
2022-05-06T18:53:12.796Z,1651863192.796 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-05-06T18:53:12.797Z,1651863192.797 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-05-06T18:53:12.797Z,1651863192.797 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-05-06T18:53:12.797Z,1651863192.797 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-05-06T18:53:12.797Z,1651863192.797 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-05-06T18:53:12.798Z,1651863192.798 [BuoyancyServo](INFO): Powering down
2022-05-06T18:53:12.812Z,1651863192.812 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-06T18:53:12.812Z,1651863192.812 [ElevatorServo](INFO): Powering down
2022-05-06T18:53:12.813Z,1651863192.813 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-06T18:53:12.813Z,1651863192.813 [MassServo](INFO): Powering down
2022-05-06T18:53:12.814Z,1651863192.814 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-06T18:53:12.814Z,1651863192.814 [RudderServo](INFO): Powering down
2022-05-06T18:53:12.815Z,1651863192.815 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-05-06T18:53:12.815Z,1651863192.815 [ThrusterHE](INFO): Powering down
2022-05-06T18:53:12.816Z,1651863192.816 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-05-06T18:53:12.816Z,1651863192.816 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-05-06T18:53:12.817Z,1651863192.817 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-05-06T18:53:12.817Z,1651863192.817 [CBIT](DEBUG): Powering off loads.
2022-05-06T18:53:12.828Z,1651863192.828 [CBIT](DEBUG): Disabling WDT.
2022-05-06T18:53:12.840Z,1651863192.840 [CBIT](DEBUG): Opening all GF detection circuits.
2022-05-06T18:53:12.841Z,1651863192.841 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:13.237Z,1651863193.237 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:13.269Z,1651863193.269 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:13.271Z,1651863193.271 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:13.278Z,1651863193.278 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:13.328Z,1651863193.328 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-06T18:53:13.391Z,1651863193.391 [logger ThreadHandler](INFO): Thread cancelled.