2022-01-21T22:49:58.567Z,1642805398.567 [Supervisor](DEBUG): Initializing supervisor.
2022-01-21T22:49:58.572Z,1642805398.572 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-01-21T22:49:58.573Z,1642805398.573 [SyncHandler](INFO): Protected caller Thread ID is 675
2022-01-21T22:49:58.573Z,1642805398.573 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-01-21T22:49:58.575Z,1642805398.575 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-01-21T22:49:58.575Z,1642805398.575 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 676
2022-01-21T22:49:58.579Z,1642805398.579 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-01-21T22:49:58.598Z,1642805398.598 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-01-21T22:49:58.599Z,1642805398.599 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-01-21T22:49:58.599Z,1642805398.599 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 677
2022-01-21T22:49:58.603Z,1642805398.603 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-01-21T22:49:58.604Z,1642805398.604 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-01-21T22:49:58.605Z,1642805398.605 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 678
2022-01-21T22:49:58.607Z,1642805398.607 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-01-21T22:49:58.608Z,1642805398.608 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-01-21T22:49:58.608Z,1642805398.608 [logger ThreadHandler](INFO): Protected caller Thread ID is 679
2022-01-21T22:49:58.612Z,1642805398.612 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-01-21T22:49:58.612Z,1642805398.612 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-01-21T22:49:58.614Z,1642805398.614 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-01-21T22:49:58.928Z,1642805398.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-01-21T22:49:58.929Z,1642805398.929 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-01-21T22:49:59.554Z,1642805399.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-01-21T22:49:59.555Z,1642805399.555 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-01-21T22:49:59.660Z,1642805399.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-01-21T22:49:59.661Z,1642805399.661 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-01-21T22:50:00.176Z,1642805400.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-01-21T22:50:00.177Z,1642805400.177 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-01-21T22:50:00.271Z,1642805400.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-01-21T22:50:00.272Z,1642805400.272 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-01-21T22:50:00.351Z,1642805400.351 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-01-21T22:50:00.713Z,1642805400.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-01-21T22:50:00.714Z,1642805400.714 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-01-21T22:50:00.859Z,1642805400.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-01-21T22:50:00.859Z,1642805400.859 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-01-21T22:50:00.982Z,1642805400.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-01-21T22:50:00.982Z,1642805400.982 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-01-21T22:50:01.206Z,1642805401.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-01-21T22:50:01.207Z,1642805401.207 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-01-21T22:50:01.665Z,1642805401.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-01-21T22:50:01.666Z,1642805401.666 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-01-21T22:50:02.016Z,1642805402.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-01-21T22:50:02.017Z,1642805402.017 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-01-21T22:50:02.094Z,1642805402.094 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-01-21T22:50:02.279Z,1642805402.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-01-21T22:50:02.279Z,1642805402.279 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-01-21T22:50:02.489Z,1642805402.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-01-21T22:50:02.490Z,1642805402.490 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-01-21T22:50:02.764Z,1642805402.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-01-21T22:50:02.766Z,1642805402.766 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2022-01-21T22:50:02.767Z,1642805402.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2022-01-21T22:50:02.870Z,1642805402.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2022-01-21T22:50:03.018Z,1642805403.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2022-01-21T22:50:03.194Z,1642805403.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2022-01-21T22:50:03.308Z,1642805403.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2022-01-21T22:50:03.502Z,1642805403.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2022-01-21T22:50:03.825Z,1642805403.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2022-01-21T22:50:04.170Z,1642805404.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-01-21T22:50:04.171Z,1642805404.171 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2022-01-21T22:50:04.301Z,1642805404.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2022-01-21T22:50:04.391Z,1642805404.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2022-01-21T22:50:04.495Z,1642805404.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2022-01-21T22:50:04.590Z,1642805404.590 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2022-01-21T22:50:04.591Z,1642805404.591 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-01-21T22:50:04.610Z,1642805404.610 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-01-21T22:50:04.706Z,1642805404.706 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-01-21T22:50:04.706Z,1642805404.706 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-01-21T22:50:04.717Z,1642805404.717 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-01-21T22:50:04.717Z,1642805404.717 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-01-21T22:50:04.761Z,1642805404.761 [DepthRateCalculator] Loaded
2022-01-21T22:50:04.761Z,1642805404.761 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-01-21T22:50:04.766Z,1642805404.766 [PitchRateCalculator] Loaded
2022-01-21T22:50:04.767Z,1642805404.767 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-01-21T22:50:04.777Z,1642805404.777 [SpeedCalculator] Loaded
2022-01-21T22:50:04.777Z,1642805404.777 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-01-21T22:50:04.790Z,1642805404.790 [TempGradientCalculator] Loaded
2022-01-21T22:50:04.790Z,1642805404.790 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-01-21T22:50:04.795Z,1642805404.795 [YawRateCalculator] Loaded
2022-01-21T22:50:04.795Z,1642805404.795 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-01-21T22:50:04.815Z,1642805404.815 [ElevatorOffsetCalculator] Loaded
2022-01-21T22:50:04.815Z,1642805404.815 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-01-21T22:50:04.816Z,1642805404.816 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-01-21T22:50:04.816Z,1642805404.816 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-01-21T22:50:04.870Z,1642805404.870 [VerticalControl](DEBUG): Construct VerticalControl.
2022-01-21T22:50:04.924Z,1642805404.924 [VerticalControl] Loaded
2022-01-21T22:50:04.924Z,1642805404.924 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-01-21T22:50:04.927Z,1642805404.927 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-01-21T22:50:04.964Z,1642805404.964 [HorizontalControl] Loaded
2022-01-21T22:50:04.964Z,1642805404.964 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-01-21T22:50:04.967Z,1642805404.967 [SpeedControl](DEBUG): Construct SpeedControl.
2022-01-21T22:50:04.969Z,1642805404.969 [SpeedControl] Loaded
2022-01-21T22:50:04.970Z,1642805404.970 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-01-21T22:50:04.972Z,1642805404.972 [LoopControl](DEBUG): Construct LoopControl.
2022-01-21T22:50:04.973Z,1642805404.973 [LoopControl] Loaded
2022-01-21T22:50:04.973Z,1642805404.973 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-01-21T22:50:04.973Z,1642805404.973 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-01-21T22:50:04.974Z,1642805404.974 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-01-21T22:50:05.081Z,1642805405.081 [DeadReckonUsingSpeedCalculator] Loaded
2022-01-21T22:50:05.082Z,1642805405.082 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-01-21T22:50:05.094Z,1642805405.094 [NavChart] Loaded
2022-01-21T22:50:05.095Z,1642805405.095 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-01-21T22:50:05.100Z,1642805405.100 [UniversalFixResidualReporter] Loaded
2022-01-21T22:50:05.100Z,1642805405.100 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-01-21T22:50:05.101Z,1642805405.101 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-01-21T22:50:05.101Z,1642805405.101 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-01-21T22:50:05.204Z,1642805405.204 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-01-21T22:50:05.205Z,1642805405.205 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-01-21T22:50:05.631Z,1642805405.631 [AHRS_M2] Loaded
2022-01-21T22:50:05.632Z,1642805405.632 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-01-21T22:50:05.666Z,1642805405.666 [BackseatComponent] Loaded
2022-01-21T22:50:05.667Z,1642805405.667 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-01-21T22:50:05.668Z,1642805405.668 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408C84E0
2022-01-21T22:50:05.668Z,1642805405.668 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 762
2022-01-21T22:50:05.671Z,1642805405.671 [LcmUniversalReporter] Loaded
2022-01-21T22:50:05.671Z,1642805405.671 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-01-21T22:50:06.460Z,1642805406.460 [BPC1] Loaded
2022-01-21T22:50:06.460Z,1642805406.460 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-01-21T22:50:06.531Z,1642805406.531 [DataOverHttps] Loaded
2022-01-21T22:50:06.532Z,1642805406.532 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-01-21T22:50:06.533Z,1642805406.533 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F84E0
2022-01-21T22:50:06.533Z,1642805406.533 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 763
2022-01-21T22:50:06.553Z,1642805406.553 [Depth_Keller] Loaded
2022-01-21T22:50:06.553Z,1642805406.553 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-01-21T22:50:06.557Z,1642805406.557 [DropWeight] Loaded
2022-01-21T22:50:06.558Z,1642805406.558 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-01-21T22:50:06.594Z,1642805406.594 [DVL_micro] Loaded
2022-01-21T22:50:06.594Z,1642805406.594 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2022-01-21T22:50:06.651Z,1642805406.651 [NAL9602] Loaded
2022-01-21T22:50:06.651Z,1642805406.651 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-01-21T22:50:06.679Z,1642805406.679 [Onboard] Loaded
2022-01-21T22:50:06.680Z,1642805406.680 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-01-21T22:50:06.681Z,1642805406.681 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409284E0
2022-01-21T22:50:06.681Z,1642805406.681 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 764
2022-01-21T22:50:06.693Z,1642805406.693 [Power24vConverter] Loaded
2022-01-21T22:50:06.693Z,1642805406.693 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-01-21T22:50:06.705Z,1642805406.705 [Radio_Surface] Loaded
2022-01-21T22:50:06.706Z,1642805406.706 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-01-21T22:50:06.707Z,1642805406.707 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409584E0
2022-01-21T22:50:06.707Z,1642805406.707 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 765
2022-01-21T22:50:06.708Z,1642805406.708 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-01-21T22:50:06.709Z,1642805406.709 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-01-21T22:50:06.834Z,1642805406.834 [BuoyancyServo] Loaded
2022-01-21T22:50:06.835Z,1642805406.835 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-01-21T22:50:06.854Z,1642805406.854 [ElevatorServo] Loaded
2022-01-21T22:50:06.854Z,1642805406.854 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-01-21T22:50:06.873Z,1642805406.873 [MassServo] Loaded
2022-01-21T22:50:06.873Z,1642805406.873 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-01-21T22:50:06.887Z,1642805406.887 [ThrusterHE] Loaded
2022-01-21T22:50:06.888Z,1642805406.888 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-01-21T22:50:06.888Z,1642805406.888 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-01-21T22:50:06.889Z,1642805406.889 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-01-21T22:50:06.946Z,1642805406.946 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-01-21T22:50:06.946Z,1642805406.946 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-01-21T22:50:06.974Z,1642805406.974 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-01-21T22:50:06.975Z,1642805406.975 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-01-21T22:50:07.297Z,1642805407.297 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-01-21T22:50:07.298Z,1642805407.298 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-01-21T22:50:07.471Z,1642805407.471 [CTD_Seabird] Loaded
2022-01-21T22:50:07.471Z,1642805407.471 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-01-21T22:50:07.472Z,1642805407.472 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AEF4E0
2022-01-21T22:50:07.473Z,1642805407.473 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 766
2022-01-21T22:50:07.504Z,1642805407.504 [ESPComponent] Loaded
2022-01-21T22:50:07.504Z,1642805407.504 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-01-21T22:50:07.522Z,1642805407.522 [PAR_Licor] Loaded
2022-01-21T22:50:07.523Z,1642805407.523 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-01-21T22:50:07.553Z,1642805407.553 [WetLabsBB2FL] Loaded
2022-01-21T22:50:07.554Z,1642805407.554 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-01-21T22:50:07.555Z,1642805407.555 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B1F4E0
2022-01-21T22:50:07.555Z,1642805407.555 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 767
2022-01-21T22:50:07.556Z,1642805407.556 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-01-21T22:50:07.556Z,1642805407.556 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-01-21T22:50:07.701Z,1642805407.701 [SBIT](DEBUG): Construct Startup Built In Test.
2022-01-21T22:50:07.710Z,1642805407.710 [SBIT] Loaded
2022-01-21T22:50:07.710Z,1642805407.710 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-01-21T22:50:07.713Z,1642805407.713 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-01-21T22:50:07.726Z,1642805407.726 [IBIT] Loaded
2022-01-21T22:50:07.726Z,1642805407.726 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-01-21T22:50:07.732Z,1642805407.732 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-01-21T22:50:07.832Z,1642805407.832 [CBIT] Loaded
2022-01-21T22:50:07.833Z,1642805407.833 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-01-21T22:50:07.833Z,1642805407.833 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-01-21T22:50:07.839Z,1642805407.839 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-01-21T22:50:07.842Z,1642805407.842 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-01-21T22:50:07.852Z,1642805407.852 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-01-21T22:50:07.853Z,1642805407.853 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B8D4E0
2022-01-21T22:50:07.854Z,1642805407.854 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 768
2022-01-21T22:50:07.858Z,1642805407.858 [Supervisor](INFO): Main Thread ID is 9886
2022-01-21T22:50:07.858Z,1642805407.858 [Supervisor](DEBUG): Running supervisor.
2022-01-21T22:50:07.859Z,1642805407.859 [CommandExec ThreadHandler](INFO): Handler Thread ID is 769
2022-01-21T22:50:07.859Z,1642805407.859 [CommandExec](INFO): Initializing the command executive.
2022-01-21T22:50:07.861Z,1642805407.861 [CommandLine ThreadHandler](INFO): Handler Thread ID is 770
2022-01-21T22:50:07.863Z,1642805407.863 [controlThread ThreadHandler](INFO): Handler Thread ID is 771
2022-01-21T22:50:07.863Z,1642805407.863 [controlThread](DEBUG): Initializing ControlThread
2022-01-21T22:50:07.864Z,1642805407.864 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-01-21T22:50:07.865Z,1642805407.865 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-01-21T22:50:07.865Z,1642805407.865 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-01-21T22:50:07.865Z,1642805407.865 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-01-21T22:50:07.866Z,1642805407.866 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-01-21T22:50:07.866Z,1642805407.866 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-01-21T22:50:07.867Z,1642805407.867 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-01-21T22:50:07.868Z,1642805407.868 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-01-21T22:50:07.869Z,1642805407.869 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-01-21T22:50:07.869Z,1642805407.869 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-01-21T22:50:07.870Z,1642805407.870 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-01-21T22:50:07.870Z,1642805407.870 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-01-21T22:50:07.871Z,1642805407.871 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-01-21T22:50:07.880Z,1642805407.880 [SBIT](INFO): Initialize SBIT Component.
2022-01-21T22:50:07.881Z,1642805407.881 [SBIT](IMPORTANT): git: 2021-12-16A-150-g8bf596bd7
2022-01-21T22:50:07.881Z,1642805407.881 [SBIT](INFO): git hash: 8bf596bd7715e6f3701bad3487aa6f7f4693db01
2022-01-21T22:50:07.881Z,1642805407.881 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-01-21T22:50:07.882Z,1642805407.882 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-01-21T22:50:07.883Z,1642805407.883 [SBIT](INFO): Beginning SBIT in 47.000000 seconds.
2022-01-21T22:50:07.884Z,1642805407.884 [logger ThreadHandler](INFO): Handler Thread ID is 772
2022-01-21T22:50:07.900Z,1642805407.900 [IBIT](INFO): Initialize IBIT Component.
2022-01-21T22:50:07.901Z,1642805407.901 [CBIT](DEBUG): Initialize CBIT Component.
2022-01-21T22:50:07.908Z,1642805407.908 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 773
2022-01-21T22:50:07.912Z,1642805407.912 [CBIT](DEBUG): Initialized mux pins.
2022-01-21T22:50:07.912Z,1642805407.912 [CBIT](DEBUG): Initializing the watchdog timer.
2022-01-21T22:50:07.920Z,1642805407.920 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 775
2022-01-21T22:50:07.921Z,1642805407.921 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-01-21T22:50:07.932Z,1642805407.932 [Onboard ThreadHandler](INFO): Handler Thread ID is 777
2022-01-21T22:50:07.936Z,1642805407.936 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-01-21T22:50:07.937Z,1642805407.937 [CBIT](DEBUG): Initializing heartbeat.
2022-01-21T22:50:07.964Z,1642805407.964 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 778
2022-01-21T22:50:07.972Z,1642805407.972 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 779
2022-01-21T22:50:07.973Z,1642805407.973 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-21T22:50:07.977Z,1642805407.977 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 781
2022-01-21T22:50:07.980Z,1642805407.980 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:50:07.981Z,1642805407.981 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 783
2022-01-21T22:50:07.984Z,1642805407.984 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000
2022-01-21T22:50:07.984Z,1642805407.984 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000
2022-01-21T22:50:07.984Z,1642805407.984 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000
2022-01-21T22:50:07.984Z,1642805407.984 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000
2022-01-21T22:50:07.984Z,1642805407.984 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000
2022-01-21T22:50:07.985Z,1642805407.985 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000
2022-01-21T22:50:07.985Z,1642805407.985 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000
2022-01-21T22:50:07.985Z,1642805407.985 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000
2022-01-21T22:50:08.008Z,1642805408.008 [CBIT](DEBUG): Deactivating GF circuits.
2022-01-21T22:50:08.008Z,1642805408.008 [CBIT](DEBUG): Deactivating emergency mode.
2022-01-21T22:50:08.044Z,1642805408.044 [CBIT](DEBUG): Backplane powered.
2022-01-21T22:50:08.045Z,1642805408.045 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-01-21T22:50:08.057Z,1642805408.057 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-21T22:50:08.077Z,1642805408.077 [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-01-21T22:50:08.077Z,1642805408.077 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-01-21T22:50:08.197Z,1642805408.197 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-01-21T22:50:08.198Z,1642805408.198 [Default:A.Wait](DEBUG): Construct Wait.
2022-01-21T22:50:08.208Z,1642805408.208 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-21T22:50:08.260Z,1642805408.260 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-01-21T22:50:08.263Z,1642805408.263 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-01-21T22:50:08.281Z,1642805408.281 [Default:E.Execute](DEBUG): Construct Execute.
2022-01-21T22:50:08.298Z,1642805408.298 [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-01-21T22:50:08.308Z,1642805408.308 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-21T22:50:08.316Z,1642805408.316 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-21T22:50:08.368Z,1642805408.368 [Radio_Surface](INFO): Powering up
2022-01-21T22:50:08.410Z,1642805408.410 [Power24vConverter](INFO): Powering up.
2022-01-21T22:50:08.465Z,1642805408.465 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-01-21T22:50:08.485Z,1642805408.485 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-01-21T22:50:08.486Z,1642805408.486 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-01-21T22:50:08.496Z,1642805408.496 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-01-21T22:50:08.497Z,1642805408.497 [MassServo](DEBUG): Initializing EZServoServo.
2022-01-21T22:50:08.504Z,1642805408.504 [MassServo](DEBUG): Initializing MassServo.
2022-01-21T22:50:08.505Z,1642805408.505 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-01-21T22:50:08.521Z,1642805408.520 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-01-21T22:50:08.737Z,1642805408.737 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-01-21T22:50:08.737Z,1642805408.737 [DropWeight] Hardware Fault, FailCount= 1
2022-01-21T22:50:08.737Z,1642805408.737 [DropWeight](ERROR): Hardware Fault
2022-01-21T22:50:08.808Z,1642805408.808 [DVL_micro](INFO): Powering down
2022-01-21T22:50:08.838Z,1642805408.838 [CommandExec](FAULT): Scheduling is paused
2022-01-21T22:50:08.839Z,1642805408.839 [CBIT](INFO): Critical error at 20220121T225008
2022-01-21T22:50:08.839Z,1642805408.839 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-01-21T22:50:08.853Z,1642805408.853 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-01-21T22:50:08.854Z,1642805408.854 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-01-21T22:50:09.153Z,1642805409.153 [CBIT](INFO): Critical error at 20220121T225008
2022-01-21T22:50:11.860Z,1642805411.860 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-01-21T22:50:12.284Z,1642805412.284 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-01-21T22:50:13.272Z,1642805413.272 [WetLabsBB2FL] Communications Fault, FailCount= 1
2022-01-21T22:50:13.272Z,1642805413.272 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:50:13.353Z,1642805413.353 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:50:13.772Z,1642805413.772 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:50:14.081Z,1642805414.081 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-01-21T22:50:14.616Z,1642805414.616 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:50:14.616Z,1642805414.616 [WetLabsBB2FL] No Fault, FailCount= 1
2022-01-21T22:50:14.884Z,1642805414.884 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:14.985Z,1642805414.985 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:50:15.528Z,1642805415.528 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:15.932Z,1642805415.932 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:16.388Z,1642805416.388 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:16.816Z,1642805416.816 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:17.252Z,1642805417.252 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:17.708Z,1642805417.708 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:18.152Z,1642805418.152 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:18.276Z,1642805418.276 [CBIT](CRITICAL): Environmental Failure. Press:14.785387 PSI. Humidity:36%. Temp:21 C. ABORTING MISSION
2022-01-21T22:50:18.608Z,1642805418.608 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:18.710Z,1642805418.710 [CBIT](INFO): Critical error at 20220121T225018
2022-01-21T22:50:19.040Z,1642805419.040 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:19.480Z,1642805419.480 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:19.960Z,1642805419.960 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:20.276Z,1642805420.276 [WetLabsBB2FL] Communications Fault, FailCount= 2
2022-01-21T22:50:20.276Z,1642805420.276 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:50:20.368Z,1642805420.368 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:20.500Z,1642805420.500 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:50:20.776Z,1642805420.776 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:50:20.832Z,1642805420.832 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:21.024Z,1642805421.024 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-01-21T22:50:21.024Z,1642805421.024 [CTD_Seabird] Communications Fault, FailCount= 1
2022-01-21T22:50:21.024Z,1642805421.024 [CTD_Seabird](ERROR): Communications Fault
2022-01-21T22:50:21.232Z,1642805421.232 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:21.232Z,1642805421.232 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-01-21T22:50:21.232Z,1642805421.232 [AHRS_M2] Communications Fault, FailCount= 1
2022-01-21T22:50:21.232Z,1642805421.232 [AHRS_M2](ERROR): Communications Fault
2022-01-21T22:50:21.328Z,1642805421.328 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-01-21T22:50:21.328Z,1642805421.328 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-01-21T22:50:21.334Z,1642805421.334 [AHRS_M2](INFO): Powering down
2022-01-21T22:50:21.429Z,1642805421.429 [CTD_Seabird](INFO): Powering down
2022-01-21T22:50:21.948Z,1642805421.948 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:50:21.948Z,1642805421.948 [WetLabsBB2FL] No Fault, FailCount= 2
2022-01-21T22:50:21.989Z,1642805421.989 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:50:22.188Z,1642805422.188 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-01-21T22:50:22.188Z,1642805422.188 [AHRS_M2] No Fault, FailCount= 1
2022-01-21T22:50:22.544Z,1642805422.544 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-21T22:50:22.624Z,1642805422.624 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-01-21T22:50:22.624Z,1642805422.624 [CTD_Seabird] No Fault, FailCount= 1
2022-01-21T22:50:22.772Z,1642805422.772 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-21T22:50:26.100Z,1642805426.100 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:26.504Z,1642805426.504 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:26.904Z,1642805426.904 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:27.288Z,1642805427.288 [WetLabsBB2FL] Communications Fault, FailCount= 3
2022-01-21T22:50:27.288Z,1642805427.288 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:50:27.320Z,1642805427.320 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:27.401Z,1642805427.401 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:50:27.740Z,1642805427.740 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:27.788Z,1642805427.788 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:50:28.132Z,1642805428.132 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:28.536Z,1642805428.536 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:28.616Z,1642805428.616 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:50:28.616Z,1642805428.616 [WetLabsBB2FL] No Fault, FailCount= 3
2022-01-21T22:50:28.940Z,1642805428.940 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:29.002Z,1642805429.002 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:50:29.344Z,1642805429.344 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:29.752Z,1642805429.752 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:30.148Z,1642805430.148 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:30.640Z,1642805430.640 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:31.032Z,1642805431.032 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:31.448Z,1642805431.448 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:31.856Z,1642805431.856 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:32.256Z,1642805432.256 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:32.660Z,1642805432.660 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:33.060Z,1642805433.060 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:33.468Z,1642805433.468 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:33.872Z,1642805433.872 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:34.276Z,1642805434.276 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:34.308Z,1642805434.308 [WetLabsBB2FL] Communications Fault, FailCount= 4
2022-01-21T22:50:34.308Z,1642805434.308 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:50:34.684Z,1642805434.684 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:34.717Z,1642805434.717 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:50:34.808Z,1642805434.808 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:50:35.092Z,1642805435.092 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:35.492Z,1642805435.492 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:35.492Z,1642805435.492 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-01-21T22:50:35.492Z,1642805435.492 [AHRS_M2] Communications Fault, FailCount= 2
2022-01-21T22:50:35.492Z,1642805435.492 [AHRS_M2](ERROR): Communications Fault
2022-01-21T22:50:35.515Z,1642805435.515 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-01-21T22:50:35.568Z,1642805435.568 [AHRS_M2](INFO): Powering down
2022-01-21T22:50:35.664Z,1642805435.664 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:50:35.665Z,1642805435.665 [WetLabsBB2FL] No Fault, FailCount= 4
2022-01-21T22:50:35.824Z,1642805435.824 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-01-21T22:50:35.824Z,1642805435.824 [CTD_Seabird] Communications Fault, FailCount= 2
2022-01-21T22:50:35.824Z,1642805435.824 [CTD_Seabird](ERROR): Communications Fault
2022-01-21T22:50:36.005Z,1642805436.005 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-01-21T22:50:36.021Z,1642805436.021 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:50:36.228Z,1642805436.228 [CTD_Seabird](INFO): Powering down
2022-01-21T22:50:36.400Z,1642805436.400 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-01-21T22:50:36.400Z,1642805436.400 [AHRS_M2] No Fault, FailCount= 2
2022-01-21T22:50:36.780Z,1642805436.780 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-21T22:50:36.790Z,1642805436.790 [NAL9602](INFO): Powering up NAL9602
2022-01-21T22:50:37.208Z,1642805437.208 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-01-21T22:50:37.208Z,1642805437.208 [CTD_Seabird] No Fault, FailCount= 2
2022-01-21T22:50:37.572Z,1642805437.572 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-21T22:50:40.336Z,1642805440.336 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:40.732Z,1642805440.732 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:41.144Z,1642805441.144 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:41.320Z,1642805441.320 [WetLabsBB2FL] Communications Fault, FailCount= 5
2022-01-21T22:50:41.320Z,1642805441.320 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:50:41.548Z,1642805441.548 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:41.564Z,1642805441.564 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:50:41.564Z,1642805441.564 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:50:41.820Z,1642805441.820 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:50:41.952Z,1642805441.952 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:42.348Z,1642805442.348 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:42.760Z,1642805442.760 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:43.164Z,1642805443.164 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:43.568Z,1642805443.568 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:43.972Z,1642805443.972 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:44.376Z,1642805444.376 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:44.780Z,1642805444.780 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:45.184Z,1642805445.184 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:45.588Z,1642805445.588 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:45.992Z,1642805445.992 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:46.396Z,1642805446.396 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:46.800Z,1642805446.800 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:47.204Z,1642805447.204 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:47.608Z,1642805447.608 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:47.622Z,1642805447.622 [NAL9602](INFO): NAL9602 initialized
2022-01-21T22:50:48.012Z,1642805448.012 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:48.416Z,1642805448.416 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:48.828Z,1642805448.828 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:49.216Z,1642805449.216 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:49.628Z,1642805449.628 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:49.628Z,1642805449.628 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-01-21T22:50:49.628Z,1642805449.628 [AHRS_M2] Communications Fault, FailCount= 3
2022-01-21T22:50:49.628Z,1642805449.628 [AHRS_M2](ERROR): Communications Fault
2022-01-21T22:50:49.682Z,1642805449.682 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-01-21T22:50:49.711Z,1642805449.711 [AHRS_M2](INFO): Powering down
2022-01-21T22:50:50.561Z,1642805450.561 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-01-21T22:50:50.561Z,1642805450.561 [AHRS_M2] No Fault, FailCount= 3
2022-01-21T22:50:50.624Z,1642805450.624 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-01-21T22:50:50.624Z,1642805450.624 [CTD_Seabird] Communications Fault, FailCount= 3
2022-01-21T22:50:50.624Z,1642805450.624 [CTD_Seabird](ERROR): Communications Fault
2022-01-21T22:50:50.920Z,1642805450.920 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-21T22:50:50.965Z,1642805450.965 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-01-21T22:50:50.966Z,1642805450.966 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-01-21T22:50:51.028Z,1642805451.028 [CTD_Seabird](INFO): Powering down
2022-01-21T22:50:54.476Z,1642805454.476 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:54.880Z,1642805454.880 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:55.284Z,1642805455.284 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:55.339Z,1642805455.339 [SBIT](IMPORTANT): Beginning Startup BIT
2022-01-21T22:50:55.345Z,1642805455.345 [CBIT](IMPORTANT): Beginning ground fault scan
2022-01-21T22:50:55.712Z,1642805455.712 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:56.140Z,1642805456.140 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:56.556Z,1642805456.556 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:56.972Z,1642805456.972 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:57.056Z,1642805457.056 [ElevatorServo](FAULT): Overload Error
2022-01-21T22:50:57.056Z,1642805457.056 [ElevatorServo] Hardware Fault, FailCount= 1
2022-01-21T22:50:57.056Z,1642805457.056 [ElevatorServo](ERROR): Hardware Fault
2022-01-21T22:50:57.097Z,1642805457.097 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2022-01-21T22:50:57.516Z,1642805457.516 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:57.536Z,1642805457.536 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-01-21T22:50:57.536Z,1642805457.536 [ElevatorServo](INFO): Powering down
2022-01-21T22:50:57.936Z,1642805457.936 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:58.348Z,1642805458.348 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:58.404Z,1642805458.404 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-01-21T22:50:58.525Z,1642805458.525 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-01-21T22:50:58.561Z,1642805458.561 [CBIT](INFO): Clearing failed state for component ElevatorServo
2022-01-21T22:50:58.561Z,1642805458.561 [ElevatorServo] No Fault, FailCount= 1
2022-01-21T22:50:58.976Z,1642805458.976 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:59.372Z,1642805459.372 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:50:59.832Z,1642805459.832 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:00.304Z,1642805460.304 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:00.756Z,1642805460.756 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:01.168Z,1642805461.168 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:01.664Z,1642805461.664 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:02.084Z,1642805462.084 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:02.520Z,1642805462.520 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:02.932Z,1642805462.932 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:03.444Z,1642805463.444 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:03.501Z,1642805463.501 [ElevatorServo](FAULT): Overload Error
2022-01-21T22:51:03.501Z,1642805463.501 [ElevatorServo] Hardware Fault, FailCount= 2
2022-01-21T22:51:03.501Z,1642805463.501 [ElevatorServo](ERROR): Hardware Fault
2022-01-21T22:51:03.557Z,1642805463.557 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2022-01-21T22:51:03.867Z,1642805463.867 [CommandExec](IMPORTANT): got command get ThrusterHE.loadAtStartup
2022-01-21T22:51:03.867Z,1642805463.867 [CommandExec](IMPORTANT): ThrusterHE.loadAtStartup 1 bool
2022-01-21T22:51:03.888Z,1642805463.888 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:03.888Z,1642805463.888 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-01-21T22:51:03.888Z,1642805463.888 [AHRS_M2] Communications Fault, FailCount= 4
2022-01-21T22:51:03.888Z,1642805463.888 [AHRS_M2](ERROR): Communications Fault
2022-01-21T22:51:03.948Z,1642805463.948 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-01-21T22:51:03.949Z,1642805463.949 [ElevatorServo](INFO): Powering down
2022-01-21T22:51:03.980Z,1642805463.980 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-01-21T22:51:03.987Z,1642805463.987 [AHRS_M2](INFO): Powering down
2022-01-21T22:51:04.408Z,1642805464.408 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-01-21T22:51:04.527Z,1642805464.527 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-01-21T22:51:04.561Z,1642805464.561 [CBIT](INFO): Clearing failed state for component ElevatorServo
2022-01-21T22:51:04.561Z,1642805464.561 [ElevatorServo] No Fault, FailCount= 2
2022-01-21T22:51:04.849Z,1642805464.849 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-01-21T22:51:04.849Z,1642805464.849 [AHRS_M2] No Fault, FailCount= 4
2022-01-21T22:51:05.196Z,1642805465.196 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-21T22:51:06.977Z,1642805466.977 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.022785
CHAN A1 (24V): -0.002091
CHAN A2 (12V): -0.000363
CHAN A3 (5V): -0.000369
CHAN B0 (3.3V): 0.000719
CHAN B1 (3.15aV): 0.000265
CHAN B2 (3.15bV): 0.000389
CHAN B3 (GND): 0.001446
OPEN: -0.001091
Full Scale: +/- 1 mA
2022-01-21T22:51:08.752Z,1642805468.752 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:08.807Z,1642805468.807 [SBIT](ERROR): Could not read rudderAngleReader_.
2022-01-21T22:51:09.164Z,1642805469.164 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:09.588Z,1642805469.588 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:09.638Z,1642805469.638 [ElevatorServo](FAULT): Overload Error
2022-01-21T22:51:09.638Z,1642805469.638 [ElevatorServo] Hardware Fault, FailCount= 3
2022-01-21T22:51:09.638Z,1642805469.638 [ElevatorServo](ERROR): Hardware Fault
2022-01-21T22:51:09.698Z,1642805469.698 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2022-01-21T22:51:09.699Z,1642805469.699 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2022-01-21T22:51:10.028Z,1642805470.028 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:10.084Z,1642805470.084 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-01-21T22:51:10.084Z,1642805470.084 [ElevatorServo](INFO): Powering down
2022-01-21T22:51:10.087Z,1642805470.087 [CBIT](INFO): Critical error at 20220121T225109
2022-01-21T22:51:10.460Z,1642805470.460 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:10.860Z,1642805470.860 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:10.902Z,1642805470.902 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-01-21T22:51:11.021Z,1642805471.021 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-01-21T22:51:11.408Z,1642805471.408 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:11.625Z,1642805471.625 [CommandExec](IMPORTANT): got command get ThrusterHE.loadAtStartup
2022-01-21T22:51:11.625Z,1642805471.625 [CommandExec](IMPORTANT): ThrusterHE.loadAtStartup 1 bool
2022-01-21T22:51:11.820Z,1642805471.820 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:12.244Z,1642805472.244 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:12.668Z,1642805472.668 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:13.072Z,1642805473.072 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:13.476Z,1642805473.476 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:13.876Z,1642805473.876 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:14.276Z,1642805474.276 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:14.684Z,1642805474.684 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:15.084Z,1642805475.084 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:15.488Z,1642805475.488 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:15.892Z,1642805475.892 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:16.300Z,1642805476.300 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:16.720Z,1642805476.720 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:17.132Z,1642805477.132 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:17.572Z,1642805477.572 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:17.972Z,1642805477.972 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:18.392Z,1642805478.392 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2022-01-21T22:51:18.392Z,1642805478.392 [AHRS_M2](FAULT): Failed to initialize within timeout.
2022-01-21T22:51:18.392Z,1642805478.392 [AHRS_M2] Communications Fault, FailCount= 5
2022-01-21T22:51:18.392Z,1642805478.392 [AHRS_M2](ERROR): Communications Fault
2022-01-21T22:51:18.445Z,1642805478.445 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2022-01-21T22:51:18.445Z,1642805478.445 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2022-01-21T22:51:18.468Z,1642805478.468 [AHRS_M2](INFO): Powering down
2022-01-21T22:51:18.594Z,1642805478.594 [CBIT](INFO): Critical error at 20220121T225118
2022-01-21T22:51:29.336Z,1642805489.336 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2022-01-21T22:51:29.339Z,1642805489.339 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2022-01-21T22:51:29.426Z,1642805489.426 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-21T22:51:35.505Z,1642805495.505 [SBIT](ERROR): Could not read elevatorAngleReader_.
2022-01-21T22:51:35.505Z,1642805495.505 [SBIT](ERROR): Could not read rudderAngleReader_.
2022-01-21T22:51:35.505Z,1642805495.505 [SBIT](FAULT): Control surface position failure.
2022-01-21T22:51:37.098Z,1642805497.098 [CommandExec](IMPORTANT): got command maintain clear
2022-01-21T22:51:37.162Z,1642805497.162 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-21T22:51:44.177Z,1642805504.177 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction -300 revolution_per_minute
2022-01-21T22:51:44.178Z,1642805504.178 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2022-01-21T22:51:44.386Z,1642805504.386 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-21T22:51:49.201Z,1642805509.201 [SBIT](ERROR): Could not read elevatorAngleReader_.
2022-01-21T22:51:49.201Z,1642805509.201 [SBIT](ERROR): Could not read rudderAngleReader_.
2022-01-21T22:51:49.201Z,1642805509.201 [SBIT](FAULT): Control surface position failure.
2022-01-21T22:51:49.640Z,1642805509.640 [SBIT](CRITICAL): SBIT FAILED
2022-01-21T22:51:49.640Z,1642805509.640 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-01-21T22:51:49.641Z,1642805509.641 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2022-01-21T22:51:49.641Z,1642805509.641 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2022-01-21T22:51:49.641Z,1642805509.641 [SBIT](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2022-01-21T22:51:49.641Z,1642805509.641 [SBIT](IMPORTANT): BuoyancyServo.limitLo=1750 count;

2022-01-21T22:51:49.641Z,1642805509.641 [SBIT](IMPORTANT): CBIT.abortDepth=15 meter;

2022-01-21T22:51:49.641Z,1642805509.641 [SBIT](IMPORTANT): CBIT.stopDepth=10 meter;

2022-01-21T22:51:49.641Z,1642805509.641 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0.01 practical_salinity_unit;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup=0 bool;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup=1 bool;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): Depth_Keller.offset=3.49 decibar;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): ESPComponent.processCompleteTimeout=120 minute;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.010000 practical_salinity_unit;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-01-21T22:51:49.642Z,1642805509.642 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 0.200000 microgram_per_liter;

2022-01-21T22:51:49.643Z,1642805509.643 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 1.000000 meter;

2022-01-21T22:51:49.643Z,1642805509.643 [SBIT](IMPORTANT): HorizontalControl.rudLimit=10 degree;

2022-01-21T22:51:49.643Z,1642805509.643 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=40 ampere_hour;

2022-01-21T22:51:49.643Z,1642805509.643 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12.5 volt;

2022-01-21T22:51:49.643Z,1642805509.643 [SBIT](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2022-01-21T22:51:49.643Z,1642805509.643 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=438.149052 cubic_centimeter;

2022-01-21T22:51:49.643Z,1642805509.643 [SBIT](IMPORTANT): VerticalControl.massDefault=6.905350 millimeter;

2022-01-21T22:51:49.645Z,1642805509.645 [CBIT](INFO): Critical error at 20220121T225149
2022-01-21T22:51:50.004Z,1642805510.004 [MissionManager](IMPORTANT): Started mission Startup
2022-01-21T22:51:50.005Z,1642805510.005 [Startup] Running Loop=1
2022-01-21T22:51:50.005Z,1642805510.005 [Startup](DEBUG): Aggregate::initialize Startup
2022-01-21T22:51:50.005Z,1642805510.005 [Startup:A.GoToSurface] Running Loop=1
2022-01-21T22:51:50.005Z,1642805510.005 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-21T22:51:50.006Z,1642805510.006 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-21T22:51:50.006Z,1642805510.006 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-21T22:51:50.006Z,1642805510.006 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-21T22:51:50.007Z,1642805510.007 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-21T22:51:50.007Z,1642805510.007 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-21T22:51:50.009Z,1642805510.009 [Startup:StartupSatComms] Running Loop=1
2022-01-21T22:51:50.009Z,1642805510.009 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-01-21T22:51:50.009Z,1642805510.009 [Startup:StartupSatComms:A] Running Loop=1
2022-01-21T22:51:50.406Z,1642805510.406 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-01-21T22:52:01.785Z,1642805521.785 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 599 revolution_per_minute
2022-01-21T22:52:04.248Z,1642805524.248 [ThrusterHE](FAULT): getSpeed error serial timeout
2022-01-21T22:52:04.248Z,1642805524.248 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2022-01-21T22:52:04.248Z,1642805524.248 [ThrusterHE] Communications Fault, FailCount= 1
2022-01-21T22:52:04.248Z,1642805524.248 [ThrusterHE](ERROR): Communications Fault
2022-01-21T22:52:04.252Z,1642805524.252 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2022-01-21T22:52:04.563Z,1642805524.563 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-01-21T22:52:04.563Z,1642805524.563 [ThrusterHE](INFO): Powering down
2022-01-21T22:52:05.354Z,1642805525.354 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-01-21T22:52:05.473Z,1642805525.473 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-01-21T22:52:05.477Z,1642805525.477 [CBIT](INFO): Clearing failed state for component ThrusterHE
2022-01-21T22:52:05.477Z,1642805525.477 [ThrusterHE] No Fault, FailCount= 1
2022-01-21T22:52:08.169Z,1642805528.169 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-01-21T22:52:08.169Z,1642805528.169 [DVL_micro] Communications Fault, FailCount= 1
2022-01-21T22:52:08.169Z,1642805528.169 [DVL_micro](ERROR): Communications Fault
2022-01-21T22:52:08.175Z,1642805528.175 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-01-21T22:52:08.176Z,1642805528.176 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2022-01-21T22:52:08.176Z,1642805528.176 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-01-21T22:52:08.186Z,1642805528.186 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-01-21T22:52:08.186Z,1642805528.186 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-01-21T22:52:08.640Z,1642805528.640 [DVL_micro](INFO): Powering down
2022-01-21T22:52:08.687Z,1642805528.687 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-01-21T22:52:08.687Z,1642805528.687 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2022-01-21T22:52:08.996Z,1642805528.996 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-01-21T22:52:09.415Z,1642805529.415 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-01-21T22:52:09.415Z,1642805529.415 [DVL_micro] No Fault, FailCount= 1
2022-01-21T22:52:10.440Z,1642805530.440 [CommandExec](IMPORTANT): got command failComponent
2022-01-21T22:52:10.441Z,1642805530.441 [CommandExec](IMPORTANT): Failed components:
2022-01-21T22:52:10.441Z,1642805530.441 [CommandExec](IMPORTANT): AHRS_M2: Communications Fault
2022-01-21T22:52:10.441Z,1642805530.441 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-01-21T22:52:10.441Z,1642805530.441 [CommandExec](IMPORTANT): ElevatorServo: Hardware Fault
2022-01-21T22:52:10.442Z,1642805530.442 [CommandExec](IMPORTANT): CTD_Seabird: Communications Fault
2022-01-21T22:52:10.442Z,1642805530.442 [CommandExec](IMPORTANT): WetLabsBB2FL: Communications Fault
2022-01-21T22:52:11.003Z,1642805531.003 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-01-21T22:52:19.828Z,1642805539.828 [CommandExec](IMPORTANT): got command failComponent
2022-01-21T22:52:19.828Z,1642805539.828 [CommandExec](IMPORTANT): Failed components:
2022-01-21T22:52:19.829Z,1642805539.829 [CommandExec](IMPORTANT): AHRS_M2: Communications Fault
2022-01-21T22:52:19.829Z,1642805539.829 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-01-21T22:52:19.829Z,1642805539.829 [CommandExec](IMPORTANT): ElevatorServo: Hardware Fault
2022-01-21T22:52:19.829Z,1642805539.829 [CommandExec](IMPORTANT): CTD_Seabird: Communications Fault
2022-01-21T22:52:19.830Z,1642805539.830 [CommandExec](IMPORTANT): WetLabsBB2FL: Communications Fault
2022-01-21T22:52:26.021Z,1642805546.021 [CommandExec](IMPORTANT): got command maintain
2022-01-21T22:52:26.021Z,1642805546.021 [Maintain_SpeedControl.propOmegaAction](INFO): SpeedControl.propOmegaAction maintaining 599.000000 rpm
2022-01-21T22:52:27.158Z,1642805547.158 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-01-21T22:52:27.158Z,1642805547.158 [DVL_micro] Communications Fault, FailCount= 2
2022-01-21T22:52:27.158Z,1642805547.158 [DVL_micro](ERROR): Communications Fault
2022-01-21T22:52:27.223Z,1642805547.223 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-01-21T22:52:27.632Z,1642805547.632 [DVL_micro](INFO): Powering down
2022-01-21T22:52:28.431Z,1642805548.431 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-01-21T22:52:28.432Z,1642805548.432 [DVL_micro] No Fault, FailCount= 2
2022-01-21T22:52:35.646Z,1642805555.646 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004546
2022-01-21T22:52:45.328Z,1642805565.328 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:52:46.157Z,1642805566.157 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-01-21T22:52:46.157Z,1642805566.157 [DVL_micro] Communications Fault, FailCount= 3
2022-01-21T22:52:46.157Z,1642805566.157 [DVL_micro](ERROR): Communications Fault
2022-01-21T22:52:46.244Z,1642805566.244 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-01-21T22:52:46.244Z,1642805566.244 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2022-01-21T22:52:46.616Z,1642805566.616 [DVL_micro](INFO): Powering down
2022-01-21T22:52:46.698Z,1642805566.698 [CBIT](INFO): Critical error at 20220121T225246
2022-01-21T22:52:50.193Z,1642805570.193 [Startup:StartupSatComms:A](INFO): Timed out from 2022-01-21T22:51:50.0Z
2022-01-21T22:52:50.193Z,1642805570.193 [Startup:StartupSatComms:A] Stopped
2022-01-21T22:52:50.193Z,1642805570.193 [Startup:StartupSatComms:B] Running Loop=1
2022-01-21T22:52:50.614Z,1642805570.614 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-01-21T22:52:56.901Z,1642805576.901 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20210831T222848/Courier0000.lzma
2022-01-21T22:52:57.902Z,1642805577.902 [DataOverHttps](INFO): Moved sent file to Logs/20210831T222848/Courier0000.lzma.bak
2022-01-21T22:52:57.902Z,1642805577.902 [DataOverHttps](INFO): SBD MOMSN=16430067
2022-01-21T22:52:59.216Z,1642805579.216 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:53:11.396Z,1642805591.396 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:53:11.396Z,1642805591.396 [WetLabsBB2FL] No Fault, FailCount= 5
2022-01-21T22:53:11.721Z,1642805591.721 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:53:13.373Z,1642805593.373 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:53:13.424Z,1642805593.424 [DataOverHttps](INFO): Sending 408 bytes from file Logs/20220121T223948/Courier0000.lzma
2022-01-21T22:53:14.338Z,1642805594.338 [DataOverHttps](INFO): Moved sent file to Logs/20220121T223948/Courier0000.lzma.bak
2022-01-21T22:53:14.338Z,1642805594.338 [DataOverHttps](INFO): SBD MOMSN=16430079
2022-01-21T22:53:17.012Z,1642805597.012 [WetLabsBB2FL] Communications Fault, FailCount= 1
2022-01-21T22:53:17.012Z,1642805597.012 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:53:17.088Z,1642805597.088 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:53:17.512Z,1642805597.512 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:53:18.681Z,1642805598.681 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:53:18.681Z,1642805598.681 [WetLabsBB2FL] No Fault, FailCount= 1
2022-01-21T22:53:18.725Z,1642805598.725 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:53:20.688Z,1642805600.688 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-01-21T22:53:20.688Z,1642805600.688 [CTD_Seabird] No Fault, FailCount= 3
2022-01-21T22:53:20.696Z,1642805600.696 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-21T22:53:24.024Z,1642805604.024 [WetLabsBB2FL] Communications Fault, FailCount= 2
2022-01-21T22:53:24.024Z,1642805604.024 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:53:24.337Z,1642805604.337 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:53:24.524Z,1642805604.524 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:53:25.536Z,1642805605.536 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:53:25.536Z,1642805605.536 [WetLabsBB2FL] No Fault, FailCount= 2
2022-01-21T22:53:25.737Z,1642805605.737 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:53:27.104Z,1642805607.104 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:53:29.909Z,1642805609.909 [DataOverHttps](INFO): Sending 400 bytes from file Logs/20220121T224202/Courier0000.lzma
2022-01-21T22:53:30.910Z,1642805610.910 [DataOverHttps](INFO): Moved sent file to Logs/20220121T224202/Courier0000.lzma.bak
2022-01-21T22:53:30.910Z,1642805610.910 [DataOverHttps](INFO): SBD MOMSN=16430091
2022-01-21T22:53:31.040Z,1642805611.040 [WetLabsBB2FL] Communications Fault, FailCount= 3
2022-01-21T22:53:31.040Z,1642805611.040 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:53:31.208Z,1642805611.208 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:53:31.544Z,1642805611.544 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:53:32.417Z,1642805612.417 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:53:32.417Z,1642805612.417 [WetLabsBB2FL] No Fault, FailCount= 3
2022-01-21T22:53:32.757Z,1642805612.757 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:53:33.756Z,1642805613.756 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-01-21T22:53:33.756Z,1642805613.756 [CTD_Seabird] Communications Fault, FailCount= 1
2022-01-21T22:53:33.756Z,1642805613.756 [CTD_Seabird](ERROR): Communications Fault
2022-01-21T22:53:34.048Z,1642805614.048 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-01-21T22:53:34.160Z,1642805614.160 [CTD_Seabird](INFO): Powering down
2022-01-21T22:53:35.258Z,1642805615.258 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-01-21T22:53:35.258Z,1642805615.258 [CTD_Seabird] No Fault, FailCount= 1
2022-01-21T22:53:35.504Z,1642805615.504 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-21T22:53:38.048Z,1642805618.048 [WetLabsBB2FL] Communications Fault, FailCount= 4
2022-01-21T22:53:38.048Z,1642805618.048 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:53:38.096Z,1642805618.096 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:53:38.548Z,1642805618.548 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:53:39.694Z,1642805619.694 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2022-01-21T22:53:39.694Z,1642805619.694 [WetLabsBB2FL] No Fault, FailCount= 4
2022-01-21T22:53:39.762Z,1642805619.762 [WetLabsBB2FL](INFO): Powering up
2022-01-21T22:53:41.244Z,1642805621.244 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:53:45.052Z,1642805625.052 [WetLabsBB2FL] Communications Fault, FailCount= 5
2022-01-21T22:53:45.052Z,1642805625.052 [WetLabsBB2FL](ERROR): Communications Fault
2022-01-21T22:53:45.319Z,1642805625.319 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220121T224202/Courier0004.lzma
2022-01-21T22:53:45.332Z,1642805625.332 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:53:45.332Z,1642805625.332 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2022-01-21T22:53:45.552Z,1642805625.552 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:53:46.274Z,1642805626.274 [DataOverHttps](INFO): Moved sent file to Logs/20220121T224202/Courier0004.lzma.bak
2022-01-21T22:53:46.274Z,1642805626.274 [DataOverHttps](INFO): SBD MOMSN=16430101
2022-01-21T22:53:48.556Z,1642805628.556 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-01-21T22:53:48.556Z,1642805628.556 [CTD_Seabird] Communications Fault, FailCount= 2
2022-01-21T22:53:48.556Z,1642805628.556 [CTD_Seabird](ERROR): Communications Fault
2022-01-21T22:53:48.613Z,1642805628.613 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-01-21T22:53:48.960Z,1642805628.960 [CTD_Seabird](INFO): Powering down
2022-01-21T22:53:50.220Z,1642805630.220 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-01-21T22:53:50.220Z,1642805630.220 [CTD_Seabird] No Fault, FailCount= 2
2022-01-21T22:53:50.300Z,1642805630.300 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-21T22:53:50.544Z,1642805630.544 [Startup:StartupSatComms:B](INFO): Timed out from 2022-01-21T22:52:50.2Z
2022-01-21T22:53:50.544Z,1642805630.544 [Startup:StartupSatComms:B] Stopped
2022-01-21T22:53:50.545Z,1642805630.545 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-01-21T22:53:50.545Z,1642805630.545 [Startup:StartupSatComms] Stopped
2022-01-21T22:53:50.545Z,1642805630.545 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-01-21T22:53:50.545Z,1642805630.545 [Startup](INFO): Completed Startup
2022-01-21T22:53:50.546Z,1642805630.546 [MissionManager](INFO): Startup is completed.
2022-01-21T22:53:50.546Z,1642805630.546 [MissionManager](INFO): Uninitializing Mission Startup
2022-01-21T22:53:50.546Z,1642805630.546 [Startup] Stopped
2022-01-21T22:53:50.546Z,1642805630.546 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-01-21T22:53:50.546Z,1642805630.546 [Startup:A.GoToSurface] Stopped
2022-01-21T22:53:50.546Z,1642805630.546 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-21T22:53:50.945Z,1642805630.945 [MissionManager](IMPORTANT): Started mission Default
2022-01-21T22:53:50.945Z,1642805630.945 [Default] Running Loop=1
2022-01-21T22:53:50.945Z,1642805630.945 [Default](DEBUG): Aggregate::initialize Default
2022-01-21T22:53:50.946Z,1642805630.946 [Default:B.GoToSurface] Running Loop=1
2022-01-21T22:53:50.946Z,1642805630.946 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-21T22:53:50.946Z,1642805630.946 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-21T22:53:50.946Z,1642805630.946 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-21T22:53:50.946Z,1642805630.946 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-21T22:53:50.947Z,1642805630.947 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-21T22:53:50.947Z,1642805630.947 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-21T22:53:50.947Z,1642805630.947 [Default:A.Wait] Running Loop=1
2022-01-21T22:53:50.948Z,1642805630.948 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-01-21T22:53:55.028Z,1642805635.028 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:54:00.617Z,1642805640.617 [CommandExec](IMPORTANT): got command maintain
2022-01-21T22:54:00.617Z,1642805640.617 [Maintain_SpeedControl.propOmegaAction](INFO): SpeedControl.propOmegaAction maintaining 599.000000 rpm
2022-01-21T22:54:01.596Z,1642805641.596 [DataOverHttps](INFO): Sending 406 bytes from file Logs/20220121T224755/Courier0000.lzma
2022-01-21T22:54:02.598Z,1642805642.598 [DataOverHttps](INFO): Moved sent file to Logs/20220121T224755/Courier0000.lzma.bak
2022-01-21T22:54:02.598Z,1642805642.598 [DataOverHttps](INFO): SBD MOMSN=16430103
2022-01-21T22:54:03.352Z,1642805643.352 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-01-21T22:54:03.352Z,1642805643.352 [CTD_Seabird] Communications Fault, FailCount= 3
2022-01-21T22:54:03.352Z,1642805643.352 [CTD_Seabird](ERROR): Communications Fault
2022-01-21T22:54:03.552Z,1642805643.552 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-01-21T22:54:03.553Z,1642805643.553 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-01-21T22:54:03.756Z,1642805643.756 [CTD_Seabird](INFO): Powering down
2022-01-21T22:54:04.317Z,1642805644.317 [Default:A.Wait](INFO): Done Waiting.
2022-01-21T22:54:04.317Z,1642805644.317 [Default:A.Wait] Stopped
2022-01-21T22:54:04.317Z,1642805644.317 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-01-21T22:54:04.727Z,1642805644.727 [Default:CheckIn] Running Loop=1
2022-01-21T22:54:04.727Z,1642805644.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-21T22:54:04.727Z,1642805644.727 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-21T22:54:05.130Z,1642805645.130 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-01-21T22:54:09.191Z,1642805649.191 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:54:09.233Z,1642805649.233 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-01-21T22:54:09.233Z,1642805649.233 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2022-01-21T22:54:09.233Z,1642805649.233 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-01-21T22:54:09.284Z,1642805649.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-01-21T22:54:09.643Z,1642805649.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-01-21T22:54:09.644Z,1642805649.644 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2022-01-21T22:54:09.993Z,1642805649.993 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-01-21T22:54:22.868Z,1642805662.868 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:54:37.009Z,1642805677.009 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:54:42.457Z,1642805682.457 [CommandExec](IMPORTANT): got command maintain clear
2022-01-21T22:54:42.742Z,1642805682.742 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-21T22:54:50.744Z,1642805690.744 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2022-01-21T22:54:52.972Z,1642805692.972 [CommandExec](IMPORTANT): got command quit
2022-01-21T22:54:53.976Z,1642805693.976 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:53.976Z,1642805693.976 [CommandExec](INFO): Uninitializing the command executive.
2022-01-21T22:54:53.976Z,1642805693.976 [CommandExec](INFO): Uninitializing the command scheduler.
2022-01-21T22:54:53.976Z,1642805693.976 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:53.984Z,1642805693.984 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-01-21T22:54:53.984Z,1642805693.984 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-01-21T22:54:53.984Z,1642805693.984 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:53.985Z,1642805693.985 [NavChartDb](INFO): Join timeout helper Thread ID is 827
2022-01-21T22:54:54.068Z,1642805694.068 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:54.068Z,1642805694.068 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.072Z,1642805694.072 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-01-21T22:54:54.072Z,1642805694.072 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.072Z,1642805694.072 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 828
2022-01-21T22:54:54.232Z,1642805694.232 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:54.232Z,1642805694.232 [WetLabsBB2FL](INFO): Powering down
2022-01-21T22:54:54.233Z,1642805694.233 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.236Z,1642805694.236 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-01-21T22:54:54.236Z,1642805694.236 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.237Z,1642805694.237 [CTD_Seabird](INFO): Join timeout helper Thread ID is 829
2022-01-21T22:54:54.388Z,1642805694.388 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:54.388Z,1642805694.388 [CTD_Seabird](INFO): Powering down
2022-01-21T22:54:54.400Z,1642805694.400 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.401Z,1642805694.401 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-01-21T22:54:54.401Z,1642805694.401 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.401Z,1642805694.401 [Radio_Surface](INFO): Join timeout helper Thread ID is 830
2022-01-21T22:54:54.480Z,1642805694.480 [Radio_Surface](INFO): Powering down
2022-01-21T22:54:54.481Z,1642805694.481 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:54.481Z,1642805694.481 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.496Z,1642805694.496 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-01-21T22:54:54.496Z,1642805694.496 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:54.496Z,1642805694.496 [Onboard](INFO): Join timeout helper Thread ID is 831
2022-01-21T22:54:54.968Z,1642805694.968 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-01-21T22:54:58.188Z,1642805698.188 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:58.188Z,1642805698.188 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:58.201Z,1642805698.201 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-01-21T22:54:58.201Z,1642805698.201 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:58.201Z,1642805698.201 [DataOverHttps](INFO): Join timeout helper Thread ID is 832
2022-01-21T22:54:58.984Z,1642805698.984 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:58.984Z,1642805698.984 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.005Z,1642805699.005 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-01-21T22:54:59.005Z,1642805699.005 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.008Z,1642805699.008 [BackseatComponent](INFO): Join timeout helper Thread ID is 833
2022-01-21T22:54:59.104Z,1642805699.104 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:59.104Z,1642805699.104 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.109Z,1642805699.109 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-01-21T22:54:59.109Z,1642805699.109 [logger ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.109Z,1642805699.109 [logger](INFO): Join timeout helper Thread ID is 834
2022-01-21T22:54:59.152Z,1642805699.152 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:59.152Z,1642805699.152 [logger ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.169Z,1642805699.169 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-01-21T22:54:59.169Z,1642805699.169 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.169Z,1642805699.169 [CommandLine](INFO): Join timeout helper Thread ID is 835
2022-01-21T22:54:59.244Z,1642805699.244 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:59.244Z,1642805699.244 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.257Z,1642805699.257 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-01-21T22:54:59.257Z,1642805699.257 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.257Z,1642805699.257 [CommandExec](INFO): Join timeout helper Thread ID is 836
2022-01-21T22:54:59.258Z,1642805699.258 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-01-21T22:54:59.258Z,1642805699.258 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.259Z,1642805699.259 [controlThread](INFO): Join timeout helper Thread ID is 837
2022-01-21T22:54:59.628Z,1642805699.628 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-21T22:54:59.628Z,1642805699.628 [controlThread](DEBUG): Uninitializing ControlThread
2022-01-21T22:54:59.628Z,1642805699.628 [AHRS_M2](INFO): Powering down
2022-01-21T22:54:59.630Z,1642805699.630 [DVL_micro](INFO): Powering down
2022-01-21T22:54:59.631Z,1642805699.631 [NAL9602](INFO): Powering down
2022-01-21T22:54:59.633Z,1642805699.633 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-01-21T22:54:59.634Z,1642805699.634 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-01-21T22:54:59.634Z,1642805699.634 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-01-21T22:54:59.635Z,1642805699.635 [MissionManager](INFO): Uninitializing Mission Default
2022-01-21T22:54:59.635Z,1642805699.635 [Default] Stopped
2022-01-21T22:54:59.635Z,1642805699.635 [Default](DEBUG): Aggregate::uninitialize Default
2022-01-21T22:54:59.635Z,1642805699.635 [Default:B.GoToSurface] Stopped
2022-01-21T22:54:59.635Z,1642805699.635 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-21T22:54:59.635Z,1642805699.635 [Default:CheckIn] Stopped
2022-01-21T22:54:59.635Z,1642805699.635 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-21T22:54:59.635Z,1642805699.635 [Default:CheckIn:Read_GPS] Stopped
2022-01-21T22:54:59.638Z,1642805699.638 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-01-21T22:54:59.638Z,1642805699.638 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-01-21T22:54:59.638Z,1642805699.638 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-01-21T22:54:59.639Z,1642805699.639 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-01-21T22:54:59.639Z,1642805699.639 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-01-21T22:54:59.639Z,1642805699.639 [BuoyancyServo](INFO): Powering down
2022-01-21T22:54:59.652Z,1642805699.652 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-01-21T22:54:59.652Z,1642805699.652 [ElevatorServo](INFO): Powering down
2022-01-21T22:54:59.653Z,1642805699.653 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-01-21T22:54:59.653Z,1642805699.653 [MassServo](INFO): Powering down
2022-01-21T22:54:59.654Z,1642805699.654 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-01-21T22:54:59.654Z,1642805699.654 [ThrusterHE](INFO): Powering down
2022-01-21T22:54:59.655Z,1642805699.655 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-01-21T22:54:59.655Z,1642805699.655 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-01-21T22:54:59.655Z,1642805699.655 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-01-21T22:54:59.655Z,1642805699.655 [CBIT](DEBUG): Powering off loads.
2022-01-21T22:54:59.668Z,1642805699.668 [CBIT](DEBUG): Disabling WDT.
2022-01-21T22:54:59.680Z,1642805699.680 [CBIT](DEBUG): Opening all GF detection circuits.
2022-01-21T22:54:59.681Z,1642805699.681 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.734Z,1642805699.734 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.737Z,1642805699.737 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.744Z,1642805699.744 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.787Z,1642805699.787 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.954Z,1642805699.954 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.959Z,1642805699.959 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:54:59.981Z,1642805699.981 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-21T22:55:00.040Z,1642805700.040 [logger ThreadHandler](INFO): Thread cancelled.