2022-05-03T16:05:51.882Z,1651593951.882 [Supervisor](DEBUG): Initializing supervisor.
2022-05-03T16:05:51.886Z,1651593951.886 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-05-03T16:05:51.886Z,1651593951.886 [SyncHandler](INFO): Protected caller Thread ID is 3353
2022-05-03T16:05:51.887Z,1651593951.887 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-05-03T16:05:51.888Z,1651593951.888 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-05-03T16:05:51.888Z,1651593951.888 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3354
2022-05-03T16:05:51.892Z,1651593951.892 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-05-03T16:05:51.910Z,1651593951.910 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-05-03T16:05:51.911Z,1651593951.911 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-05-03T16:05:51.911Z,1651593951.911 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3355
2022-05-03T16:05:51.915Z,1651593951.915 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-05-03T16:05:51.917Z,1651593951.917 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-05-03T16:05:51.917Z,1651593951.917 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3356
2022-05-03T16:05:51.919Z,1651593951.919 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-05-03T16:05:51.920Z,1651593951.920 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-05-03T16:05:51.920Z,1651593951.920 [logger ThreadHandler](INFO): Protected caller Thread ID is 3357
2022-05-03T16:05:51.924Z,1651593951.924 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-05-03T16:05:51.924Z,1651593951.924 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-05-03T16:05:51.926Z,1651593951.926 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-05-03T16:05:52.018Z,1651593952.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-05-03T16:05:52.019Z,1651593952.019 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-05-03T16:05:52.231Z,1651593952.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-05-03T16:05:52.231Z,1651593952.231 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-05-03T16:05:52.483Z,1651593952.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-05-03T16:05:52.483Z,1651593952.483 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-05-03T16:05:52.589Z,1651593952.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-05-03T16:05:52.590Z,1651593952.590 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-05-03T16:05:53.300Z,1651593953.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-05-03T16:05:53.300Z,1651593953.300 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-05-03T16:05:53.744Z,1651593953.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-05-03T16:05:53.745Z,1651593953.745 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-05-03T16:05:54.303Z,1651593954.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-05-03T16:05:54.304Z,1651593954.304 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-05-03T16:05:54.679Z,1651593954.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-05-03T16:05:54.680Z,1651593954.680 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-05-03T16:05:55.002Z,1651593955.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-05-03T16:05:55.003Z,1651593955.003 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-05-03T16:05:55.362Z,1651593955.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-05-03T16:05:55.363Z,1651593955.363 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-05-03T16:05:55.578Z,1651593955.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-05-03T16:05:55.579Z,1651593955.579 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-05-03T16:05:55.705Z,1651593955.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-05-03T16:05:55.705Z,1651593955.705 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-05-03T16:05:55.784Z,1651593955.784 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-05-03T16:05:55.862Z,1651593955.862 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-05-03T16:05:56.045Z,1651593956.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-05-03T16:05:56.046Z,1651593956.046 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-05-03T16:05:56.260Z,1651593956.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-05-03T16:05:56.262Z,1651593956.262 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2022-05-03T16:05:56.263Z,1651593956.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2022-05-03T16:05:56.344Z,1651593956.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2022-05-03T16:05:56.637Z,1651593956.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-05-03T16:05:56.637Z,1651593956.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2022-05-03T16:05:56.840Z,1651593956.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2022-05-03T16:05:57.240Z,1651593957.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2022-05-03T16:05:57.479Z,1651593957.479 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2022-05-03T16:05:57.563Z,1651593957.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2022-05-03T16:05:57.665Z,1651593957.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2022-05-03T16:05:57.764Z,1651593957.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2022-05-03T16:05:57.919Z,1651593957.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2022-05-03T16:05:58.018Z,1651593958.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2022-05-03T16:05:58.116Z,1651593958.116 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2022-05-03T16:05:58.116Z,1651593958.116 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-05-03T16:05:58.125Z,1651593958.125 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-05-03T16:05:58.269Z,1651593958.269 [InternalSim] Loaded
2022-05-03T16:05:58.270Z,1651593958.270 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2022-05-03T16:05:58.270Z,1651593958.270 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-05-03T16:05:58.271Z,1651593958.271 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-05-03T16:05:58.395Z,1651593958.395 [BuoyancyServo] Loaded
2022-05-03T16:05:58.395Z,1651593958.395 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-05-03T16:05:58.414Z,1651593958.414 [MassServo] Loaded
2022-05-03T16:05:58.414Z,1651593958.414 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-05-03T16:05:58.428Z,1651593958.428 [ThrusterHE] Loaded
2022-05-03T16:05:58.428Z,1651593958.428 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-05-03T16:05:58.429Z,1651593958.429 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-05-03T16:05:58.429Z,1651593958.429 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-05-03T16:05:58.570Z,1651593958.570 [SBIT](DEBUG): Construct Startup Built In Test.
2022-05-03T16:05:58.585Z,1651593958.585 [SBIT] Loaded
2022-05-03T16:05:58.585Z,1651593958.585 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-05-03T16:05:58.588Z,1651593958.588 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-05-03T16:05:58.606Z,1651593958.606 [IBIT] Loaded
2022-05-03T16:05:58.606Z,1651593958.606 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-05-03T16:05:58.612Z,1651593958.612 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-05-03T16:05:58.823Z,1651593958.823 [CBIT] Loaded
2022-05-03T16:05:58.823Z,1651593958.823 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-05-03T16:05:58.823Z,1651593958.823 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-05-03T16:05:58.824Z,1651593958.824 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-05-03T16:05:58.990Z,1651593958.990 [CTD_Seabird] Loaded
2022-05-03T16:05:58.990Z,1651593958.990 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-05-03T16:05:58.991Z,1651593958.991 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-05-03T16:05:58.992Z,1651593958.992 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3440
2022-05-03T16:05:59.022Z,1651593959.022 [ESPComponent] Loaded
2022-05-03T16:05:59.023Z,1651593959.023 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-05-03T16:05:59.041Z,1651593959.041 [PAR_Licor] Loaded
2022-05-03T16:05:59.041Z,1651593959.041 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-05-03T16:05:59.042Z,1651593959.042 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-05-03T16:05:59.043Z,1651593959.043 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-05-03T16:05:59.110Z,1651593959.110 [DepthRateCalculator] Loaded
2022-05-03T16:05:59.110Z,1651593959.110 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-05-03T16:05:59.115Z,1651593959.115 [PitchRateCalculator] Loaded
2022-05-03T16:05:59.115Z,1651593959.115 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-05-03T16:05:59.125Z,1651593959.125 [SpeedCalculator] Loaded
2022-05-03T16:05:59.126Z,1651593959.126 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-05-03T16:05:59.130Z,1651593959.130 [YawRateCalculator] Loaded
2022-05-03T16:05:59.131Z,1651593959.131 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-05-03T16:05:59.149Z,1651593959.149 [ElevatorOffsetCalculator] Loaded
2022-05-03T16:05:59.149Z,1651593959.149 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-05-03T16:05:59.149Z,1651593959.149 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-05-03T16:05:59.150Z,1651593959.150 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-05-03T16:05:59.266Z,1651593959.266 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-05-03T16:05:59.267Z,1651593959.267 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-05-03T16:05:59.305Z,1651593959.305 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-05-03T16:05:59.305Z,1651593959.305 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-05-03T16:05:59.434Z,1651593959.434 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-05-03T16:05:59.434Z,1651593959.434 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-05-03T16:05:59.447Z,1651593959.447 [NavChart] Loaded
2022-05-03T16:05:59.448Z,1651593959.448 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-05-03T16:05:59.453Z,1651593959.453 [UniversalFixResidualReporter] Loaded
2022-05-03T16:05:59.454Z,1651593959.454 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-05-03T16:05:59.454Z,1651593959.454 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-05-03T16:05:59.455Z,1651593959.455 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-05-03T16:06:00.645Z,1651593960.645 [BPC1] Loaded
2022-05-03T16:06:00.645Z,1651593960.645 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-05-03T16:06:00.717Z,1651593960.717 [DataOverHttps] Loaded
2022-05-03T16:06:00.717Z,1651593960.717 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-05-03T16:06:00.718Z,1651593960.718 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2022-05-03T16:06:00.719Z,1651593960.719 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3441
2022-05-03T16:06:00.739Z,1651593960.739 [Depth_Keller] Loaded
2022-05-03T16:06:00.739Z,1651593960.739 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-05-03T16:06:00.744Z,1651593960.744 [DropWeight] Loaded
2022-05-03T16:06:00.744Z,1651593960.744 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-05-03T16:06:00.801Z,1651593960.801 [NAL9602] Loaded
2022-05-03T16:06:00.801Z,1651593960.801 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-05-03T16:06:00.825Z,1651593960.825 [Onboard] Loaded
2022-05-03T16:06:00.826Z,1651593960.826 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-05-03T16:06:00.827Z,1651593960.827 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0
2022-05-03T16:06:00.827Z,1651593960.827 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3442
2022-05-03T16:06:00.838Z,1651593960.838 [Power24vConverter] Loaded
2022-05-03T16:06:00.839Z,1651593960.839 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-05-03T16:06:00.851Z,1651593960.851 [Radio_Surface] Loaded
2022-05-03T16:06:00.851Z,1651593960.851 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-05-03T16:06:00.852Z,1651593960.852 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A3D4E0
2022-05-03T16:06:00.853Z,1651593960.853 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3443
2022-05-03T16:06:00.854Z,1651593960.854 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-05-03T16:06:00.854Z,1651593960.854 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-05-03T16:06:00.932Z,1651593960.932 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-05-03T16:06:00.933Z,1651593960.933 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-05-03T16:06:00.996Z,1651593960.996 [VerticalControl](DEBUG): Construct VerticalControl.
2022-05-03T16:06:01.047Z,1651593961.047 [VerticalControl] Loaded
2022-05-03T16:06:01.047Z,1651593961.047 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-05-03T16:06:01.050Z,1651593961.050 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-05-03T16:06:01.085Z,1651593961.085 [HorizontalControl] Loaded
2022-05-03T16:06:01.085Z,1651593961.085 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-05-03T16:06:01.088Z,1651593961.088 [SpeedControl](DEBUG): Construct SpeedControl.
2022-05-03T16:06:01.089Z,1651593961.089 [SpeedControl] Loaded
2022-05-03T16:06:01.090Z,1651593961.090 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-05-03T16:06:01.092Z,1651593961.092 [LoopControl](DEBUG): Construct LoopControl.
2022-05-03T16:06:01.093Z,1651593961.093 [LoopControl] Loaded
2022-05-03T16:06:01.093Z,1651593961.093 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-05-03T16:06:01.093Z,1651593961.093 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-05-03T16:06:01.094Z,1651593961.094 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-05-03T16:06:01.107Z,1651593961.107 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-05-03T16:06:01.108Z,1651593961.108 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-05-03T16:06:01.460Z,1651593961.460 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-05-03T16:06:01.467Z,1651593961.467 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-05-03T16:06:01.469Z,1651593961.469 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-05-03T16:06:01.480Z,1651593961.480 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-05-03T16:06:01.481Z,1651593961.481 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0
2022-05-03T16:06:01.481Z,1651593961.481 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3444
2022-05-03T16:06:01.486Z,1651593961.486 [Supervisor](INFO): Main Thread ID is 3352
2022-05-03T16:06:01.486Z,1651593961.486 [Supervisor](DEBUG): Running supervisor.
2022-05-03T16:06:01.487Z,1651593961.487 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3445
2022-05-03T16:06:01.487Z,1651593961.487 [CommandExec](INFO): Initializing the command executive.
2022-05-03T16:06:01.489Z,1651593961.489 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3446
2022-05-03T16:06:01.491Z,1651593961.491 [controlThread ThreadHandler](INFO): Handler Thread ID is 3447
2022-05-03T16:06:01.491Z,1651593961.491 [controlThread](DEBUG): Initializing ControlThread
2022-05-03T16:06:01.492Z,1651593961.492 [InternalSim](DEBUG):  InternalSim initializing...
2022-05-03T16:06:01.498Z,1651593961.498 [InternalSim](DEBUG): oceanModelVarCount=0
2022-05-03T16:06:01.527Z,1651593961.527 [logger ThreadHandler](INFO): Handler Thread ID is 3448
2022-05-03T16:06:01.529Z,1651593961.529 [SBIT](INFO): Initialize SBIT Component.
2022-05-03T16:06:01.529Z,1651593961.529 [SBIT](IMPORTANT): git: 2022-04-25-20-g406d02f31
2022-05-03T16:06:01.530Z,1651593961.530 [SBIT](INFO): git hash: 406d02f31b66eac9f14d911d6fba954c81bae2f7
2022-05-03T16:06:01.530Z,1651593961.530 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-05-03T16:06:01.531Z,1651593961.531 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-05-03T16:06:01.532Z,1651593961.532 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2022-05-03T16:06:01.556Z,1651593961.556 [IBIT](INFO): Initialize IBIT Component.
2022-05-03T16:06:01.557Z,1651593961.557 [CBIT](DEBUG): Initialize CBIT Component.
2022-05-03T16:06:01.558Z,1651593961.558 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3449
2022-05-03T16:06:01.558Z,1651593961.558 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:06:01.565Z,1651593961.565 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3451
2022-05-03T16:06:01.566Z,1651593961.566 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-05-03T16:06:01.568Z,1651593961.568 [CBIT](DEBUG): Initialized mux pins.
2022-05-03T16:06:01.568Z,1651593961.568 [CBIT](DEBUG): Initializing the watchdog timer.
2022-05-03T16:06:01.580Z,1651593961.580 [Onboard ThreadHandler](INFO): Handler Thread ID is 3452
2022-05-03T16:06:01.592Z,1651593961.592 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-05-03T16:06:01.593Z,1651593961.593 [CBIT](DEBUG): Initializing heartbeat.
2022-05-03T16:06:01.605Z,1651593961.605 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3453
2022-05-03T16:06:01.615Z,1651593961.615 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3454
2022-05-03T16:06:01.630Z,1651593961.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2022-05-03T16:06:01.630Z,1651593961.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2022-05-03T16:06:01.630Z,1651593961.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2022-05-03T16:06:01.630Z,1651593961.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2022-05-03T16:06:01.630Z,1651593961.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000
2022-05-03T16:06:01.630Z,1651593961.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000
2022-05-03T16:06:01.631Z,1651593961.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000
2022-05-03T16:06:01.631Z,1651593961.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000
2022-05-03T16:06:01.631Z,1651593961.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000
2022-05-03T16:06:01.631Z,1651593961.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000
2022-05-03T16:06:01.631Z,1651593961.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000
2022-05-03T16:06:01.631Z,1651593961.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000
2022-05-03T16:06:01.664Z,1651593961.664 [CBIT](DEBUG): Deactivating GF circuits.
2022-05-03T16:06:01.664Z,1651593961.664 [CBIT](DEBUG): Deactivating emergency mode.
2022-05-03T16:06:01.700Z,1651593961.700 [CBIT](DEBUG): Backplane powered.
2022-05-03T16:06:01.704Z,1651593961.704 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-05-03T16:06:01.705Z,1651593961.705 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-05-03T16:06:01.705Z,1651593961.705 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-05-03T16:06:01.705Z,1651593961.705 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-05-03T16:06:01.706Z,1651593961.706 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-05-03T16:06:01.707Z,1651593961.707 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-05-03T16:06:01.707Z,1651593961.707 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-05-03T16:06:01.715Z,1651593961.715 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-05-03T16:06:01.721Z,1651593961.721 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-05-03T16:06:01.722Z,1651593961.722 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-05-03T16:06:01.722Z,1651593961.722 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-05-03T16:06:01.723Z,1651593961.723 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-05-03T16:06:01.969Z,1651593961.969 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-03T16:06:02.012Z,1651593962.012 [Radio_Surface](INFO): Powering up
2022-05-03T16:06:02.018Z,1651593962.018 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2022-05-03T16:06:02.019Z,1651593962.019 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-05-03T16:06:02.088Z,1651593962.088 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-05-03T16:06:02.089Z,1651593962.089 [Default:A.Wait](DEBUG): Construct Wait.
2022-05-03T16:06:02.092Z,1651593962.092 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-03T16:06:02.135Z,1651593962.135 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-05-03T16:06:02.138Z,1651593962.138 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-05-03T16:06:02.168Z,1651593962.168 [Default:E.Execute](DEBUG): Construct Execute.
2022-05-03T16:06:02.202Z,1651593962.202 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2022-05-03T16:06:02.206Z,1651593962.206 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,ESPComponent,PAR_Licor,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-05-03T16:06:02.304Z,1651593962.304 [Depth_Keller](ERROR): Pressure reading out of range: 1896.040405 decibar
2022-05-03T16:06:02.306Z,1651593962.306 [Power24vConverter](INFO): Powering up.
2022-05-03T16:06:02.345Z,1651593962.345 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:02.352Z,1651593962.352 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-05-03T16:06:02.354Z,1651593962.354 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:02.360Z,1651593962.360 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:06:02.361Z,1651593962.361 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:02.368Z,1651593962.368 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-05-03T16:06:02.674Z,1651593962.674 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-05-03T16:06:02.674Z,1651593962.674 [DropWeight] Hardware Fault, FailCount= 1
2022-05-03T16:06:02.674Z,1651593962.674 [DropWeight](ERROR): Hardware Fault
2022-05-03T16:06:02.720Z,1651593962.720 [CommandExec](FAULT): Scheduling is paused
2022-05-03T16:06:02.721Z,1651593962.721 [CBIT](INFO): Critical error at 20220503T160602
2022-05-03T16:06:02.721Z,1651593962.721 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-05-03T16:06:02.723Z,1651593962.723 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-05-03T16:06:02.732Z,1651593962.732 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-05-03T16:06:03.050Z,1651593963.050 [CBIT](INFO): Critical error at 20220503T160602
2022-05-03T16:06:06.728Z,1651593966.728 [MassServo](FAULT): Overload Error
2022-05-03T16:06:06.728Z,1651593966.728 [MassServo] Hardware Fault, FailCount= 1
2022-05-03T16:06:06.728Z,1651593966.728 [MassServo](ERROR): Hardware Fault
2022-05-03T16:06:06.732Z,1651593966.732 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:06:07.096Z,1651593967.096 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:06:07.096Z,1651593967.096 [MassServo](INFO): Powering down
2022-05-03T16:06:08.036Z,1651593968.036 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:08.184Z,1651593968.184 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:06:08.185Z,1651593968.185 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-05-03T16:06:08.251Z,1651593968.251 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:06:08.251Z,1651593968.251 [MassServo] No Fault, FailCount= 1
2022-05-03T16:06:11.628Z,1651593971.628 [CBIT](CRITICAL): Environmental Failure. Press:14.715568 PSI. Humidity:28%. Temp:28 C. ABORTING MISSION
2022-05-03T16:06:11.978Z,1651593971.978 [CBIT](INFO): Critical error at 20220503T160611
2022-05-03T16:06:12.380Z,1651593972.380 [MassServo](FAULT): Overload Error
2022-05-03T16:06:12.380Z,1651593972.380 [MassServo] Hardware Fault, FailCount= 2
2022-05-03T16:06:12.380Z,1651593972.380 [MassServo](ERROR): Hardware Fault
2022-05-03T16:06:12.384Z,1651593972.384 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:06:12.780Z,1651593972.780 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:06:12.780Z,1651593972.780 [MassServo](INFO): Powering down
2022-05-03T16:06:13.569Z,1651593973.569 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:13.720Z,1651593973.720 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:06:13.724Z,1651593973.724 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:06:13.724Z,1651593973.724 [MassServo] No Fault, FailCount= 2
2022-05-03T16:06:14.608Z,1651593974.608 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:06:14.608Z,1651593974.608 [CTD_Seabird] Communications Fault, FailCount= 1
2022-05-03T16:06:14.608Z,1651593974.608 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:06:14.828Z,1651593974.828 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:06:15.013Z,1651593975.013 [CTD_Seabird](INFO): Powering down
2022-05-03T16:06:15.996Z,1651593975.996 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:06:15.996Z,1651593975.996 [CTD_Seabird] No Fault, FailCount= 1
2022-05-03T16:06:16.360Z,1651593976.360 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:06:18.075Z,1651593978.075 [MassServo](FAULT): Overload Error
2022-05-03T16:06:18.075Z,1651593978.075 [MassServo] Hardware Fault, FailCount= 3
2022-05-03T16:06:18.076Z,1651593978.076 [MassServo](ERROR): Hardware Fault
2022-05-03T16:06:18.079Z,1651593978.079 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:06:18.421Z,1651593978.421 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:06:18.421Z,1651593978.421 [MassServo](INFO): Powering down
2022-05-03T16:06:19.231Z,1651593979.231 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:19.345Z,1651593979.345 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:06:19.349Z,1651593979.349 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:06:19.349Z,1651593979.349 [MassServo] No Fault, FailCount= 3
2022-05-03T16:06:23.692Z,1651593983.692 [MassServo](FAULT): Overload Error
2022-05-03T16:06:23.692Z,1651593983.692 [MassServo] Hardware Fault, FailCount= 4
2022-05-03T16:06:23.692Z,1651593983.692 [MassServo](ERROR): Hardware Fault
2022-05-03T16:06:23.696Z,1651593983.696 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:06:24.047Z,1651593984.047 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:06:24.047Z,1651593984.047 [MassServo](INFO): Powering down
2022-05-03T16:06:24.905Z,1651593984.905 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:25.025Z,1651593985.025 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:06:25.029Z,1651593985.029 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:06:25.029Z,1651593985.029 [MassServo] No Fault, FailCount= 4
2022-05-03T16:06:29.331Z,1651593989.331 [MassServo](FAULT): Overload Error
2022-05-03T16:06:29.331Z,1651593989.331 [MassServo] Hardware Fault, FailCount= 5
2022-05-03T16:06:29.332Z,1651593989.332 [MassServo](ERROR): Hardware Fault
2022-05-03T16:06:29.335Z,1651593989.335 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:06:29.336Z,1651593989.336 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2022-05-03T16:06:29.412Z,1651593989.412 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:06:29.412Z,1651593989.412 [CTD_Seabird] Communications Fault, FailCount= 2
2022-05-03T16:06:29.412Z,1651593989.412 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:06:29.711Z,1651593989.711 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:06:29.712Z,1651593989.712 [MassServo](INFO): Powering down
2022-05-03T16:06:29.714Z,1651593989.714 [CBIT](INFO): Critical error at 20220503T160629
2022-05-03T16:06:29.717Z,1651593989.717 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:06:29.816Z,1651593989.816 [CTD_Seabird](INFO): Powering down
2022-05-03T16:06:30.508Z,1651593990.508 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:06:30.625Z,1651593990.625 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:06:30.935Z,1651593990.935 [NAL9602](INFO): Powering up NAL9602
2022-05-03T16:06:31.039Z,1651593991.039 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:06:31.039Z,1651593991.039 [CTD_Seabird] No Fault, FailCount= 2
2022-05-03T16:06:31.156Z,1651593991.156 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:06:31.737Z,1651593991.737 [CBIT](IMPORTANT): Fuelgauge reading: 132167784

2022-05-03T16:06:36.789Z,1651593996.789 [CommandExec](IMPORTANT): got command show variable ampH
2022-05-03T16:06:36.894Z,1651593996.894 [CommandExec](IMPORTANT): CBIT.ampHoursUsed (ampere_hour)
2022-05-03T16:06:41.815Z,1651594001.815 [NAL9602](INFO): NAL9602 initialized
2022-05-03T16:06:44.220Z,1651594004.220 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:06:44.220Z,1651594004.220 [CTD_Seabird] Communications Fault, FailCount= 3
2022-05-03T16:06:44.220Z,1651594004.220 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:06:44.296Z,1651594004.296 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:06:44.297Z,1651594004.297 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-05-03T16:06:44.652Z,1651594004.652 [CTD_Seabird](INFO): Powering down
2022-05-03T16:06:45.543Z,1651594005.543 [CommandExec](IMPORTANT): got command report mod CBIT.ampHoursUsed
2022-05-03T16:06:45.892Z,1651594005.892 [Reporter](INFO): CBIT.ampHoursUsed 132.167786 Ah
2022-05-03T16:06:49.813Z,1651594009.813 [CommandExec](IMPORTANT): got command strobe off
2022-05-03T16:06:49.813Z,1651594009.813 [CommandExec](IMPORTANT): Deactivating strobe
2022-05-03T16:07:01.257Z,1651594021.257 [SBIT](IMPORTANT): Beginning Startup BIT
2022-05-03T16:07:01.261Z,1651594021.261 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-03T16:07:01.442Z,1651594021.442 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2022-05-03T16:07:01.445Z,1651594021.445 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2022-05-03T16:07:01.445Z,1651594021.445 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.847 degree
2022-05-03T16:07:01.449Z,1651594021.449 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2022-05-03T16:07:01.689Z,1651594021.689 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,ESPComponent,PAR_Licor,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-05-03T16:07:02.048Z,1651594022.048 [CBIT](IMPORTANT): Fuelgauge reading: 132170681

2022-05-03T16:07:02.053Z,1651594022.053 [Reporter](INFO): CBIT.ampHoursUsed 132.170670 Ah
2022-05-03T16:07:08.475Z,1651594028.475 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:07:08.476Z,1651594028.476 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:07:09.689Z,1651594029.689 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:07:12.160Z,1651594032.160 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007584
CHAN A1 (24V): 0.000309
CHAN A2 (12V): -0.005550
CHAN A3 (5V): -0.002764
CHAN B0 (3.3V): -0.000833
CHAN B1 (3.15aV): -0.001284
CHAN B2 (3.15bV): -0.001490
CHAN B3 (GND): 0.000109
OPEN: -0.000891
Full Scale: +/- 1 mA
2022-05-03T16:07:13.179Z,1651594033.179 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1651594022 second
2022-05-03T16:07:19.798Z,1651594039.798 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:07:19.799Z,1651594039.799 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:07:23.022Z,1651594043.022 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:07:24.637Z,1651594044.637 [CommandExec](IMPORTANT): got command show stack
2022-05-03T16:07:24.637Z,1651594044.637 [CommandExec](IMPORTANT): Behavior Stack:

2022-05-03T16:07:24.637Z,1651594044.637 [MissionManager](IMPORTANT): Mission loaded, but not running.
2022-05-03T16:07:31.509Z,1651594051.509 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:07:31.510Z,1651594051.510 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:07:32.359Z,1651594052.359 [CBIT](IMPORTANT): Fuelgauge reading: 132173675

2022-05-03T16:07:32.363Z,1651594052.363 [Reporter](INFO): CBIT.ampHoursUsed 132.173676 Ah
2022-05-03T16:07:36.353Z,1651594056.353 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:07:43.622Z,1651594063.622 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:07:43.624Z,1651594063.624 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:07:50.498Z,1651594070.498 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:07:55.346Z,1651594075.346 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:07:55.347Z,1651594075.347 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:07:55.362Z,1651594075.362 [SBIT](IMPORTANT): SBIT PASSED
2022-05-03T16:07:55.363Z,1651594075.363 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-05-03T16:07:55.363Z,1651594075.363 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2022-05-03T16:07:55.364Z,1651594075.364 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2022-05-03T16:07:55.364Z,1651594075.364 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2022-05-03T16:07:55.364Z,1651594075.364 [SBIT](IMPORTANT): Express linearApproximation CBIT.ampHoursUsed 1.000000 ampere_hour;

2022-05-03T16:07:55.364Z,1651594075.364 [SBIT](IMPORTANT): InternalSim.loadAtStartup=1 bool;

2022-05-03T16:07:55.364Z,1651594075.364 [SBIT](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2022-05-03T16:07:55.364Z,1651594075.364 [SBIT](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2022-05-03T16:07:55.365Z,1651594075.365 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2022-05-03T16:07:55.778Z,1651594075.778 [MissionManager](IMPORTANT): Started mission Startup
2022-05-03T16:07:55.778Z,1651594075.778 [Startup] Running Loop=1
2022-05-03T16:07:55.778Z,1651594075.778 [Startup](DEBUG): Aggregate::initialize Startup
2022-05-03T16:07:55.778Z,1651594075.778 [Startup:A.GoToSurface] Running Loop=1
2022-05-03T16:07:55.778Z,1651594075.778 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-03T16:07:55.779Z,1651594075.779 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-03T16:07:55.779Z,1651594075.779 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-03T16:07:55.780Z,1651594075.780 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-03T16:07:55.780Z,1651594075.780 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-03T16:07:55.781Z,1651594075.781 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-03T16:07:55.782Z,1651594075.782 [Startup:StartupSatComms] Running Loop=1
2022-05-03T16:07:55.782Z,1651594075.782 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-05-03T16:07:55.782Z,1651594075.782 [Startup:StartupSatComms:A] Running Loop=1
2022-05-03T16:07:56.188Z,1651594076.188 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-05-03T16:07:56.188Z,1651594076.188 [Startup:StartupSatComms:A] Stopped
2022-05-03T16:07:56.188Z,1651594076.188 [Startup:StartupSatComms:B] Running Loop=1
2022-05-03T16:07:56.561Z,1651594076.561 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-05-03T16:08:01.812Z,1651594081.812 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-05-03T16:08:01.812Z,1651594081.812 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-05-03T16:08:01.812Z,1651594081.812 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-03T16:08:01.825Z,1651594081.825 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-03T16:08:02.238Z,1651594082.238 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-03T16:08:02.238Z,1651594082.238 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0
2022-05-03T16:08:02.648Z,1651594082.648 [CBIT](IMPORTANT): Fuelgauge reading: 132177500

2022-05-03T16:08:02.668Z,1651594082.668 [Reporter](INFO): CBIT.ampHoursUsed 132.177505 Ah
2022-05-03T16:08:03.830Z,1651594083.830 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:08:07.054Z,1651594087.054 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:08:07.055Z,1651594087.055 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:08:13.315Z,1651594093.315 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005512
2022-05-03T16:08:17.158Z,1651594097.158 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:08:18.369Z,1651594098.369 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:08:18.370Z,1651594098.370 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:08:20.759Z,1651594100.759 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20220502T151229/Courier0142.lzma
2022-05-03T16:08:21.765Z,1651594101.765 [DataOverHttps](INFO): Moved sent file to Logs/20220502T151229/Courier0142.lzma.bak
2022-05-03T16:08:21.765Z,1651594101.765 [DataOverHttps](INFO): SBD MOMSN=16683790
2022-05-03T16:08:30.082Z,1651594110.082 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-05-03T16:08:30.083Z,1651594110.083 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:08:30.921Z,1651594110.921 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:08:32.941Z,1651594112.941 [CBIT](IMPORTANT): Fuelgauge reading: 132181661

2022-05-03T16:08:32.945Z,1651594112.945 [Reporter](INFO): CBIT.ampHoursUsed 132.181671 Ah
2022-05-03T16:08:37.303Z,1651594117.303 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220502T151229/Courier0251.lzma
2022-05-03T16:08:38.306Z,1651594118.306 [DataOverHttps](INFO): Moved sent file to Logs/20220502T151229/Courier0251.lzma.bak
2022-05-03T16:08:38.306Z,1651594118.306 [DataOverHttps](INFO): SBD MOMSN=16683794
2022-05-03T16:08:43.418Z,1651594123.418 [BPC1](ERROR): BPC1A: No match for serial number 0451 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:08:44.237Z,1651594124.237 [BPC1](ERROR): BPC1B: No match for serial number 03FE in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:08:54.153Z,1651594134.153 [DataOverHttps](INFO): Sending 1028 bytes from file Logs/20220503T160343/Courier0000.lzma
2022-05-03T16:08:55.154Z,1651594135.154 [DataOverHttps](INFO): Moved sent file to Logs/20220503T160343/Courier0000.lzma.bak
2022-05-03T16:08:55.154Z,1651594135.154 [DataOverHttps](INFO): SBD MOMSN=16683796
2022-05-03T16:08:56.388Z,1651594136.388 [Startup:StartupSatComms:B](INFO): Timed out from 2022-05-03T16:07:56.2Z
2022-05-03T16:08:56.388Z,1651594136.388 [Startup:StartupSatComms:B] Stopped
2022-05-03T16:08:56.388Z,1651594136.388 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-05-03T16:08:56.388Z,1651594136.388 [Startup:StartupSatComms] Stopped
2022-05-03T16:08:56.389Z,1651594136.389 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-05-03T16:08:56.389Z,1651594136.389 [Startup](INFO): Completed Startup
2022-05-03T16:08:56.389Z,1651594136.389 [MissionManager](INFO): Startup is completed.
2022-05-03T16:08:56.390Z,1651594136.390 [MissionManager](INFO): Uninitializing Mission Startup
2022-05-03T16:08:56.390Z,1651594136.390 [Startup] Stopped
2022-05-03T16:08:56.390Z,1651594136.390 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-05-03T16:08:56.390Z,1651594136.390 [Startup:A.GoToSurface] Stopped
2022-05-03T16:08:56.390Z,1651594136.390 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-03T16:08:56.887Z,1651594136.887 [MissionManager](IMPORTANT): Started mission Default
2022-05-03T16:08:56.887Z,1651594136.887 [Default] Running Loop=1
2022-05-03T16:08:56.887Z,1651594136.887 [Default](DEBUG): Aggregate::initialize Default
2022-05-03T16:08:56.887Z,1651594136.887 [Default:B.GoToSurface] Running Loop=1
2022-05-03T16:08:56.887Z,1651594136.887 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-03T16:08:56.887Z,1651594136.887 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-03T16:08:56.888Z,1651594136.888 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-03T16:08:56.888Z,1651594136.888 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-03T16:08:56.889Z,1651594136.889 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-03T16:08:56.889Z,1651594136.889 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-03T16:08:56.889Z,1651594136.889 [Default:A.Wait] Running Loop=1
2022-05-03T16:08:56.889Z,1651594136.889 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-05-03T16:08:57.154Z,1651594137.154 [BPC1](ERROR): BPC1A: No match for serial number 0451 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-05-03T16:08:57.569Z,1651594137.569 [BPC1](ERROR): BPC1B: No match for serial number 03FE in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-05-03T16:08:57.569Z,1651594137.569 [BPC1](FAULT): Failed to parse data from all battery packs.
2022-05-03T16:08:57.569Z,1651594137.569 [BPC1] Data Fault, FailCount= 1
2022-05-03T16:08:57.569Z,1651594137.569 [BPC1](ERROR): Data Fault
2022-05-03T16:08:57.589Z,1651594137.589 [CBIT](ERROR): Data Fault in component: BPC1
2022-05-03T16:09:03.252Z,1651594143.252 [CBIT](IMPORTANT): Fuelgauge reading: 132185556

2022-05-03T16:09:03.256Z,1651594143.256 [Reporter](INFO): CBIT.ampHoursUsed 132.185562 Ah
2022-05-03T16:09:10.095Z,1651594150.095 [Default:A.Wait](INFO): Done Waiting.
2022-05-03T16:09:10.095Z,1651594150.095 [Default:A.Wait] Stopped
2022-05-03T16:09:10.095Z,1651594150.095 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-05-03T16:09:10.493Z,1651594150.493 [Default:CheckIn] Running Loop=1
2022-05-03T16:09:10.493Z,1651594150.493 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-05-03T16:09:10.493Z,1651594150.493 [Default:CheckIn:Read_GPS] Running Loop=1
2022-05-03T16:09:10.967Z,1651594150.967 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-05-03T16:09:14.552Z,1651594154.552 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:09:14.553Z,1651594154.553 [CTD_Seabird] No Fault, FailCount= 3
2022-05-03T16:09:14.728Z,1651594154.728 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:09:27.780Z,1651594167.780 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:09:27.780Z,1651594167.780 [CTD_Seabird] Communications Fault, FailCount= 1
2022-05-03T16:09:27.780Z,1651594167.780 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:09:27.920Z,1651594167.920 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:09:28.184Z,1651594168.184 [CTD_Seabird](INFO): Powering down
2022-05-03T16:09:29.507Z,1651594169.507 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:09:29.507Z,1651594169.507 [CTD_Seabird] No Fault, FailCount= 1
2022-05-03T16:09:29.544Z,1651594169.544 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:09:32.711Z,1651594172.711 [DataOverHttps](INFO): Sending 387 bytes from file Logs/20220503T160551/Courier0000.lzma
2022-05-03T16:09:33.547Z,1651594173.547 [CBIT](IMPORTANT): Fuelgauge reading: 132189879

2022-05-03T16:09:33.550Z,1651594173.550 [Reporter](INFO): CBIT.ampHoursUsed 132.189880 Ah
2022-05-03T16:09:33.714Z,1651594173.714 [DataOverHttps](INFO): Moved sent file to Logs/20220503T160551/Courier0000.lzma.bak
2022-05-03T16:09:33.714Z,1651594173.714 [DataOverHttps](INFO): SBD MOMSN=16684114
2022-05-03T16:09:42.604Z,1651594182.604 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:09:42.604Z,1651594182.604 [CTD_Seabird] Communications Fault, FailCount= 2
2022-05-03T16:09:42.604Z,1651594182.604 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:09:42.826Z,1651594182.826 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:09:43.008Z,1651594183.008 [CTD_Seabird](INFO): Powering down
2022-05-03T16:09:44.059Z,1651594184.059 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:09:44.059Z,1651594184.059 [CTD_Seabird] No Fault, FailCount= 2
2022-05-03T16:09:44.348Z,1651594184.348 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:09:49.495Z,1651594189.495 [CommandExec](IMPORTANT): got command get latitude
2022-05-03T16:09:49.496Z,1651594189.496 [CommandExec](IMPORTANT): latitude 36.799999 arcdeg
2022-05-03T16:09:57.400Z,1651594197.400 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:09:57.400Z,1651594197.400 [CTD_Seabird] Communications Fault, FailCount= 3
2022-05-03T16:09:57.400Z,1651594197.400 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:09:57.796Z,1651594197.796 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:09:57.797Z,1651594197.797 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-05-03T16:09:57.804Z,1651594197.804 [CTD_Seabird](INFO): Powering down
2022-05-03T16:10:03.016Z,1651594203.016 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-05-03T16:10:03.016Z,1651594203.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-05-03T16:10:03.016Z,1651594203.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-03T16:10:03.028Z,1651594203.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-03T16:10:03.438Z,1651594203.438 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-03T16:10:03.438Z,1651594203.438 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0
2022-05-03T16:10:03.831Z,1651594203.831 [CBIT](IMPORTANT): Fuelgauge reading: 132193545

2022-05-03T16:10:03.864Z,1651594203.864 [Reporter](INFO): CBIT.ampHoursUsed 132.193542 Ah
2022-05-03T16:10:34.142Z,1651594234.142 [CBIT](IMPORTANT): Fuelgauge reading: 132197942

2022-05-03T16:10:34.151Z,1651594234.151 [Reporter](INFO): CBIT.ampHoursUsed 132.197937 Ah
2022-05-03T16:11:04.489Z,1651594264.489 [CBIT](IMPORTANT): Fuelgauge reading: 132201825

2022-05-03T16:11:04.493Z,1651594264.493 [Reporter](INFO): CBIT.ampHoursUsed 132.201828 Ah
2022-05-03T16:11:29.551Z,1651594289.551 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:11:29.551Z,1651594289.551 [MassServo] No Fault, FailCount= 5
2022-05-03T16:11:33.172Z,1651594293.172 [MassServo](FAULT): Overload Error
2022-05-03T16:11:33.172Z,1651594293.172 [MassServo] Hardware Fault, FailCount= 1
2022-05-03T16:11:33.172Z,1651594293.172 [MassServo](ERROR): Hardware Fault
2022-05-03T16:11:33.176Z,1651594293.176 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:11:33.456Z,1651594293.456 [CommandExec](IMPORTANT): got command failComponent
2022-05-03T16:11:33.457Z,1651594293.457 [CommandExec](IMPORTANT): Failed components:
2022-05-03T16:11:33.457Z,1651594293.457 [CommandExec](IMPORTANT): MassServo: Hardware Fault
2022-05-03T16:11:33.457Z,1651594293.457 [CommandExec](IMPORTANT): CTD_Seabird: Communications Fault
2022-05-03T16:11:33.457Z,1651594293.457 [CommandExec](IMPORTANT): BPC1: Data Fault
2022-05-03T16:11:33.458Z,1651594293.458 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-05-03T16:11:33.584Z,1651594293.584 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:11:33.584Z,1651594293.584 [MassServo](INFO): Powering down
2022-05-03T16:11:34.368Z,1651594294.368 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:11:34.485Z,1651594294.485 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:11:34.491Z,1651594294.491 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:11:34.491Z,1651594294.491 [MassServo] No Fault, FailCount= 1
2022-05-03T16:11:34.766Z,1651594294.766 [CBIT](IMPORTANT): Fuelgauge reading: 132206525

2022-05-03T16:11:34.770Z,1651594294.770 [Reporter](INFO): CBIT.ampHoursUsed 132.206528 Ah
2022-05-03T16:11:38.812Z,1651594298.812 [MassServo](FAULT): Overload Error
2022-05-03T16:11:38.812Z,1651594298.812 [MassServo] Hardware Fault, FailCount= 2
2022-05-03T16:11:38.812Z,1651594298.812 [MassServo](ERROR): Hardware Fault
2022-05-03T16:11:38.816Z,1651594298.816 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:11:39.193Z,1651594299.193 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:11:39.193Z,1651594299.193 [MassServo](INFO): Powering down
2022-05-03T16:11:40.004Z,1651594300.004 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:11:40.121Z,1651594300.121 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:11:40.125Z,1651594300.125 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:11:40.125Z,1651594300.125 [MassServo] No Fault, FailCount= 2
2022-05-03T16:11:44.480Z,1651594304.480 [MassServo](FAULT): Overload Error
2022-05-03T16:11:44.480Z,1651594304.480 [MassServo] Hardware Fault, FailCount= 3
2022-05-03T16:11:44.480Z,1651594304.480 [MassServo](ERROR): Hardware Fault
2022-05-03T16:11:44.517Z,1651594304.517 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:11:44.872Z,1651594304.872 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:11:44.872Z,1651594304.872 [MassServo](INFO): Powering down
2022-05-03T16:11:45.626Z,1651594305.626 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-05-03T16:11:45.667Z,1651594305.667 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:11:45.785Z,1651594305.785 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:11:45.789Z,1651594305.789 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:11:45.789Z,1651594305.789 [MassServo] No Fault, FailCount= 3
2022-05-03T16:11:49.748Z,1651594309.748 [MassServo](FAULT): Overload Error
2022-05-03T16:11:49.748Z,1651594309.748 [MassServo] Hardware Fault, FailCount= 4
2022-05-03T16:11:49.748Z,1651594309.748 [MassServo](ERROR): Hardware Fault
2022-05-03T16:11:49.752Z,1651594309.752 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:11:50.087Z,1651594310.087 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:11:50.087Z,1651594310.087 [MassServo](INFO): Powering down
2022-05-03T16:11:50.913Z,1651594310.913 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:11:51.029Z,1651594311.029 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:11:51.033Z,1651594311.033 [CBIT](INFO): Clearing failed state for component MassServo
2022-05-03T16:11:51.033Z,1651594311.033 [MassServo] No Fault, FailCount= 4
2022-05-03T16:11:54.000Z,1651594315.000 [MassServo](FAULT): Overload Error
2022-05-03T16:11:55.000Z,1651594315.000 [MassServo] Hardware Fault, FailCount= 5
2022-05-03T16:11:55.000Z,1651594315.000 [MassServo](ERROR): Hardware Fault
2022-05-03T16:11:55.044Z,1651594315.044 [CBIT](ERROR): Hardware Fault in component: MassServo
2022-05-03T16:11:55.044Z,1651594315.044 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2022-05-03T16:11:55.361Z,1651594315.361 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-03T16:11:55.361Z,1651594315.361 [MassServo](INFO): Powering down
2022-05-03T16:11:55.363Z,1651594315.363 [CBIT](INFO): Critical error at 20220503T161155
2022-05-03T16:11:56.151Z,1651594316.151 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-03T16:11:56.265Z,1651594316.265 [MassServo](DEBUG): Initializing MassServo.
2022-05-03T16:12:03.860Z,1651594323.860 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-05-03T16:12:03.860Z,1651594323.860 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-05-03T16:12:03.860Z,1651594323.860 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-03T16:12:03.925Z,1651594323.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-03T16:12:04.258Z,1651594324.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-03T16:12:04.258Z,1651594324.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0
2022-05-03T16:12:05.095Z,1651594325.095 [CBIT](IMPORTANT): Fuelgauge reading: 132210202

2022-05-03T16:12:05.098Z,1651594325.098 [Reporter](INFO): CBIT.ampHoursUsed 132.210205 Ah
2022-05-03T16:12:05.373Z,1651594325.373 [CommandExec](IMPORTANT): got command configSet MassServo.loadAtStartup 0 bool persist
2022-05-03T16:12:05.373Z,1651594325.373 [CommandExec](IMPORTANT): configSet MassServo.loadAtStartup requires a restart to take effect.
2022-05-03T16:12:05.376Z,1651594325.376 [CommandExec](IMPORTANT): got command configSet DropWeight.loadAtStartup 0 bool persist
2022-05-03T16:12:05.377Z,1651594325.377 [CommandExec](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect.
2022-05-03T16:12:07.352Z,1651594327.352 [CommandExec](IMPORTANT): got command failComponent
2022-05-03T16:12:07.353Z,1651594327.353 [CommandExec](IMPORTANT): Failed components:
2022-05-03T16:12:07.353Z,1651594327.353 [CommandExec](IMPORTANT): MassServo: Hardware Fault
2022-05-03T16:12:07.353Z,1651594327.353 [CommandExec](IMPORTANT): CTD_Seabird: Communications Fault
2022-05-03T16:12:07.353Z,1651594327.353 [CommandExec](IMPORTANT): BPC1: Data Fault
2022-05-03T16:12:07.353Z,1651594327.353 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-05-03T16:12:25.037Z,1651594345.037 [CommandExec](IMPORTANT): got command configSet CTD_Seabird.loadAtStartup 0 bool persist
2022-05-03T16:12:25.037Z,1651594345.037 [CommandExec](IMPORTANT): configSet CTD_Seabird.loadAtStartup requires a restart to take effect.
2022-05-03T16:12:27.676Z,1651594347.676 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:12:27.676Z,1651594347.676 [CTD_Seabird] No Fault, FailCount= 3
2022-05-03T16:12:27.824Z,1651594347.824 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:12:29.629Z,1651594349.629 [CommandExec](IMPORTANT): got command failComponent none BPC1
2022-05-03T16:12:29.630Z,1651594349.630 [BPC1] No Fault, FailCount= 1
2022-05-03T16:12:29.630Z,1651594349.630 [CommandExec](IMPORTANT): BPC1 failureMode is No Fault
2022-05-03T16:12:35.341Z,1651594355.341 [CBIT](IMPORTANT): Fuelgauge reading: 132213979

2022-05-03T16:12:35.345Z,1651594355.345 [Reporter](INFO): CBIT.ampHoursUsed 132.213974 Ah
2022-05-03T16:12:40.876Z,1651594360.876 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:12:40.876Z,1651594360.876 [CTD_Seabird] Communications Fault, FailCount= 1
2022-05-03T16:12:40.877Z,1651594360.877 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:12:41.023Z,1651594361.023 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:12:41.280Z,1651594361.280 [CTD_Seabird](INFO): Powering down
2022-05-03T16:12:42.624Z,1651594362.624 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:12:42.624Z,1651594362.624 [CTD_Seabird] No Fault, FailCount= 1
2022-05-03T16:12:42.628Z,1651594362.628 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:12:55.688Z,1651594375.688 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:12:55.688Z,1651594375.688 [CTD_Seabird] Communications Fault, FailCount= 2
2022-05-03T16:12:55.688Z,1651594375.688 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:12:55.951Z,1651594375.951 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:12:56.092Z,1651594376.092 [CTD_Seabird](INFO): Powering down
2022-05-03T16:12:57.181Z,1651594377.181 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-05-03T16:12:57.181Z,1651594377.181 [CTD_Seabird] No Fault, FailCount= 2
2022-05-03T16:12:57.432Z,1651594377.432 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-03T16:13:05.637Z,1651594385.637 [CBIT](IMPORTANT): Fuelgauge reading: 132217259

2022-05-03T16:13:05.641Z,1651594385.641 [Reporter](INFO): CBIT.ampHoursUsed 132.217255 Ah
2022-05-03T16:13:10.484Z,1651594390.484 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-03T16:13:10.484Z,1651594390.484 [CTD_Seabird] Communications Fault, FailCount= 3
2022-05-03T16:13:10.484Z,1651594390.484 [CTD_Seabird](ERROR): Communications Fault
2022-05-03T16:13:10.537Z,1651594390.537 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-05-03T16:13:10.537Z,1651594390.537 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-05-03T16:13:10.888Z,1651594390.888 [CTD_Seabird](INFO): Powering down
2022-05-03T16:13:29.385Z,1651594409.385 [CommandExec](IMPORTANT): got command show variable vehicleOpen
2022-05-03T16:13:29.468Z,1651594409.468 [CommandExec](IMPORTANT): CBIT.vehicleOpen (bool)
2022-05-03T16:13:35.959Z,1651594415.959 [CBIT](IMPORTANT): Fuelgauge reading: 132220977

2022-05-03T16:13:35.962Z,1651594415.962 [Reporter](INFO): CBIT.ampHoursUsed 132.220978 Ah
2022-05-03T16:13:37.265Z,1651594417.265 [DataOverHttps](IMPORTANT): SBD MTMSN=20220503T161334
2022-05-03T16:13:39.268Z,1651594419.268 [CommandExec](IMPORTANT): got command configSet CBIT.vehicleOpen 1 bool persist
2022-05-03T16:13:44.576Z,1651594424.576 [DataOverHttps](INFO): Received command: restart sys
2022-05-03T16:13:44.578Z,1651594424.578 [CommandExec](IMPORTANT): got command restart system
2022-05-03T16:13:46.648Z,1651594426.648 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-03T16:13:46.648Z,1651594426.648 [CommandExec](INFO): Uninitializing the command executive.
2022-05-03T16:13:46.648Z,1651594426.648 [CommandExec](INFO): Uninitializing the command scheduler.
2022-05-03T16:13:46.649Z,1651594426.649 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-03T16:13:46.772Z,1651594426.772 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-05-03T16:13:46.772Z,1651594426.772 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-05-03T16:13:46.772Z,1651594426.772 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-03T16:13:46.773Z,1651594426.773 [NavChartDb](INFO): Join timeout helper Thread ID is 3499
2022-05-03T16:13:47.076Z,1651594427.076 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-03T16:13:47.076Z,1651594427.076 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-03T16:13:47.099Z,1651594427.099 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-05-03T16:13:47.099Z,1651594427.099 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-03T16:13:47.099Z,1651594427.099 [Radio_Surface](INFO): Join timeout helper Thread ID is 3500
2022-05-03T16:13:47.420Z,1651594427.420 [Radio_Surface](INFO): Powering down
2022-05-03T16:13:47.421Z,1651594427.421 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-03T16:13:47.421Z,1651594427.421 [Radio_Surface ThreadHandle