2023-02-21T20:58:43.761Z,1677013123.761 [Supervisor](DEBUG): Initializing supervisor.
2023-02-21T20:58:43.766Z,1677013123.766 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-02-21T20:58:43.766Z,1677013123.766 [SyncHandler](INFO): Protected caller Thread ID is 8746
2023-02-21T20:58:43.767Z,1677013123.767 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-02-21T20:58:43.768Z,1677013123.768 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-02-21T20:58:43.768Z,1677013123.768 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8747
2023-02-21T20:58:43.773Z,1677013123.773 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-02-21T20:58:43.790Z,1677013123.790 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-02-21T20:58:43.791Z,1677013123.791 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-02-21T20:58:43.791Z,1677013123.791 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8748
2023-02-21T20:58:43.796Z,1677013123.796 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-02-21T20:58:43.797Z,1677013123.797 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-02-21T20:58:43.797Z,1677013123.797 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8749
2023-02-21T20:58:43.799Z,1677013123.799 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-02-21T20:58:43.800Z,1677013123.800 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-02-21T20:58:43.800Z,1677013123.800 [logger ThreadHandler](INFO): Protected caller Thread ID is 8750
2023-02-21T20:58:43.804Z,1677013123.804 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-02-21T20:58:43.804Z,1677013123.804 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-02-21T20:58:43.806Z,1677013123.806 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-02-21T20:58:43.901Z,1677013123.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-02-21T20:58:43.902Z,1677013123.902 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-02-21T20:58:44.112Z,1677013124.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-02-21T20:58:44.112Z,1677013124.112 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-02-21T20:58:44.358Z,1677013124.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-02-21T20:58:44.359Z,1677013124.359 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-02-21T20:58:44.466Z,1677013124.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-02-21T20:58:44.467Z,1677013124.467 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-02-21T20:58:45.122Z,1677013125.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-02-21T20:58:45.123Z,1677013125.123 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-02-21T20:58:45.582Z,1677013125.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-02-21T20:58:45.583Z,1677013125.583 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-02-21T20:58:46.048Z,1677013126.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-02-21T20:58:46.049Z,1677013126.049 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-02-21T20:58:46.431Z,1677013126.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-02-21T20:58:46.432Z,1677013126.432 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-02-21T20:58:46.745Z,1677013126.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-02-21T20:58:46.746Z,1677013126.746 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-02-21T20:58:47.103Z,1677013127.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-02-21T20:58:47.104Z,1677013127.104 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-02-21T20:58:47.321Z,1677013127.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-02-21T20:58:47.321Z,1677013127.321 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-02-21T20:58:47.458Z,1677013127.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-02-21T20:58:47.459Z,1677013127.459 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-02-21T20:58:47.540Z,1677013127.540 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-02-21T20:58:47.620Z,1677013127.620 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-02-21T20:58:47.804Z,1677013127.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-02-21T20:58:47.805Z,1677013127.805 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-02-21T20:58:48.051Z,1677013128.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-02-21T20:58:48.053Z,1677013128.053 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-02-21T20:58:48.054Z,1677013128.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-02-21T20:58:48.168Z,1677013128.168 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-02-21T20:58:48.477Z,1677013128.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-02-21T20:58:48.477Z,1677013128.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-02-21T20:58:48.602Z,1677013128.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-02-21T20:58:49.047Z,1677013129.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-02-21T20:58:49.225Z,1677013129.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-02-21T20:58:49.309Z,1677013129.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-02-21T20:58:49.415Z,1677013129.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-02-21T20:58:49.517Z,1677013129.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-02-21T20:58:49.669Z,1677013129.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-02-21T20:58:49.769Z,1677013129.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-02-21T20:58:49.868Z,1677013129.868 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2023-02-21T20:58:49.868Z,1677013129.868 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-02-21T20:58:49.883Z,1677013129.883 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-02-21T20:58:50.029Z,1677013130.029 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-02-21T20:58:50.029Z,1677013130.029 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-02-21T20:58:50.185Z,1677013130.185 [BuoyancyServo] Loaded
2023-02-21T20:58:50.185Z,1677013130.185 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-02-21T20:58:50.206Z,1677013130.206 [ElevatorServo] Loaded
2023-02-21T20:58:50.206Z,1677013130.206 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-02-21T20:58:50.226Z,1677013130.226 [MassServo] Loaded
2023-02-21T20:58:50.226Z,1677013130.226 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-02-21T20:58:50.246Z,1677013130.246 [RudderServo] Loaded
2023-02-21T20:58:50.246Z,1677013130.246 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-02-21T20:58:50.262Z,1677013130.262 [ThrusterHE] Loaded
2023-02-21T20:58:50.262Z,1677013130.262 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-02-21T20:58:50.262Z,1677013130.262 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-02-21T20:58:50.263Z,1677013130.263 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-02-21T20:58:50.404Z,1677013130.404 [SBIT](DEBUG): Construct Startup Built In Test.
2023-02-21T20:58:50.419Z,1677013130.419 [SBIT] Loaded
2023-02-21T20:58:50.419Z,1677013130.419 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-02-21T20:58:50.421Z,1677013130.421 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-02-21T20:58:50.442Z,1677013130.442 [IBIT] Loaded
2023-02-21T20:58:50.442Z,1677013130.442 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-02-21T20:58:50.448Z,1677013130.448 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-02-21T20:58:50.663Z,1677013130.663 [CBIT] Loaded
2023-02-21T20:58:50.663Z,1677013130.663 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-02-21T20:58:50.663Z,1677013130.663 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-02-21T20:58:50.664Z,1677013130.664 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-02-21T20:58:50.802Z,1677013130.802 [ESPComponent] Loaded
2023-02-21T20:58:50.802Z,1677013130.802 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-02-21T20:58:50.822Z,1677013130.822 [PAR_Licor] Loaded
2023-02-21T20:58:50.822Z,1677013130.822 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-02-21T20:58:50.823Z,1677013130.823 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-02-21T20:58:50.823Z,1677013130.823 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-02-21T20:58:50.872Z,1677013130.872 [DepthRateCalculator] Loaded
2023-02-21T20:58:50.873Z,1677013130.873 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-02-21T20:58:50.878Z,1677013130.878 [PitchRateCalculator] Loaded
2023-02-21T20:58:50.878Z,1677013130.878 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-02-21T20:58:50.888Z,1677013130.888 [SpeedCalculator] Loaded
2023-02-21T20:58:50.888Z,1677013130.888 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-02-21T20:58:50.893Z,1677013130.893 [YawRateCalculator] Loaded
2023-02-21T20:58:50.893Z,1677013130.893 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-02-21T20:58:50.912Z,1677013130.912 [ElevatorOffsetCalculator] Loaded
2023-02-21T20:58:50.913Z,1677013130.913 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-02-21T20:58:50.913Z,1677013130.913 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-02-21T20:58:50.914Z,1677013130.914 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-02-21T20:58:51.012Z,1677013131.012 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-02-21T20:58:51.013Z,1677013131.013 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-02-21T20:58:51.039Z,1677013131.039 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-02-21T20:58:51.040Z,1677013131.040 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-02-21T20:58:51.148Z,1677013131.148 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-02-21T20:58:51.148Z,1677013131.148 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-02-21T20:58:51.162Z,1677013131.162 [NavChart] Loaded
2023-02-21T20:58:51.162Z,1677013131.162 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-02-21T20:58:51.167Z,1677013131.167 [UniversalFixResidualReporter] Loaded
2023-02-21T20:58:51.168Z,1677013131.168 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-02-21T20:58:51.168Z,1677013131.168 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-02-21T20:58:51.169Z,1677013131.169 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-02-21T20:58:51.574Z,1677013131.574 [AHRS_M2] Loaded
2023-02-21T20:58:51.574Z,1677013131.574 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-02-21T20:58:51.608Z,1677013131.608 [BackseatComponent] Loaded
2023-02-21T20:58:51.608Z,1677013131.608 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-02-21T20:58:51.609Z,1677013131.609 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409B54E0
2023-02-21T20:58:51.610Z,1677013131.610 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8832
2023-02-21T20:58:51.613Z,1677013131.613 [LcmUniversalReporter] Loaded
2023-02-21T20:58:51.613Z,1677013131.613 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-02-21T20:58:52.336Z,1677013132.336 [BPC1] Loaded
2023-02-21T20:58:52.336Z,1677013132.336 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-02-21T20:58:52.410Z,1677013132.410 [DataOverHttps] Loaded
2023-02-21T20:58:52.410Z,1677013132.410 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-02-21T20:58:52.411Z,1677013132.411 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2023-02-21T20:58:52.411Z,1677013132.411 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8833
2023-02-21T20:58:52.432Z,1677013132.432 [Depth_Keller] Loaded
2023-02-21T20:58:52.432Z,1677013132.432 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-02-21T20:58:52.491Z,1677013132.491 [NAL9602] Loaded
2023-02-21T20:58:52.492Z,1677013132.492 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-02-21T20:58:52.517Z,1677013132.517 [Onboard] Loaded
2023-02-21T20:58:52.517Z,1677013132.517 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-02-21T20:58:52.518Z,1677013132.518 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2023-02-21T20:58:52.519Z,1677013132.519 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8834
2023-02-21T20:58:52.531Z,1677013132.531 [Power24vConverter] Loaded
2023-02-21T20:58:52.531Z,1677013132.531 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-02-21T20:58:52.544Z,1677013132.544 [Radio_Surface] Loaded
2023-02-21T20:58:52.544Z,1677013132.544 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-02-21T20:58:52.545Z,1677013132.545 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0
2023-02-21T20:58:52.546Z,1677013132.546 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8835
2023-02-21T20:58:52.637Z,1677013132.637 [DAT] Loaded
2023-02-21T20:58:52.637Z,1677013132.637 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-02-21T20:58:52.638Z,1677013132.638 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A754E0
2023-02-21T20:58:52.638Z,1677013132.638 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8836
2023-02-21T20:58:52.639Z,1677013132.639 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-02-21T20:58:52.639Z,1677013132.639 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-02-21T20:58:52.695Z,1677013132.695 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-02-21T20:58:52.695Z,1677013132.695 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-02-21T20:58:52.748Z,1677013132.748 [VerticalControl](DEBUG): Construct VerticalControl.
2023-02-21T20:58:52.798Z,1677013132.798 [VerticalControl] Loaded
2023-02-21T20:58:52.798Z,1677013132.798 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-02-21T20:58:52.801Z,1677013132.801 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-02-21T20:58:52.837Z,1677013132.837 [HorizontalControl] Loaded
2023-02-21T20:58:52.838Z,1677013132.838 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-02-21T20:58:52.840Z,1677013132.840 [SpeedControl](DEBUG): Construct SpeedControl.
2023-02-21T20:58:52.842Z,1677013132.842 [SpeedControl] Loaded
2023-02-21T20:58:52.842Z,1677013132.842 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-02-21T20:58:52.845Z,1677013132.845 [LoopControl](DEBUG): Construct LoopControl.
2023-02-21T20:58:52.845Z,1677013132.845 [LoopControl] Loaded
2023-02-21T20:58:52.846Z,1677013132.846 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-02-21T20:58:52.846Z,1677013132.846 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-02-21T20:58:52.846Z,1677013132.846 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-02-21T20:58:52.857Z,1677013132.857 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-02-21T20:58:52.857Z,1677013132.857 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-02-21T20:58:53.163Z,1677013133.163 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-02-21T20:58:53.180Z,1677013133.180 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-02-21T20:58:53.183Z,1677013133.183 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-02-21T20:58:53.273Z,1677013133.273 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-02-21T20:58:53.303Z,1677013133.303 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6F4E0
2023-02-21T20:58:53.303Z,1677013133.303 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8837
2023-02-21T20:58:53.320Z,1677013133.320 [Supervisor](INFO): Main Thread ID is 6340
2023-02-21T20:58:53.320Z,1677013133.320 [Supervisor](DEBUG): Running supervisor.
2023-02-21T20:58:53.321Z,1677013133.321 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8838
2023-02-21T20:58:53.321Z,1677013133.321 [CommandExec](INFO): Initializing the command executive.
2023-02-21T20:58:53.332Z,1677013133.332 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8839
2023-02-21T20:58:53.335Z,1677013133.335 [controlThread ThreadHandler](INFO): Handler Thread ID is 8840
2023-02-21T20:58:53.336Z,1677013133.336 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8842
2023-02-21T20:58:53.337Z,1677013133.337 [controlThread](DEBUG): Initializing ControlThread
2023-02-21T20:58:53.338Z,1677013133.338 [SBIT](INFO): Initialize SBIT Component.
2023-02-21T20:58:53.339Z,1677013133.339 [SBIT](IMPORTANT): git: 2023-02-15A-1-g98c560e2e
2023-02-21T20:58:53.339Z,1677013133.339 [SBIT](INFO): git hash: 98c560e2e862bfc439cbc2881c3a6b20e1bb1b72
2023-02-21T20:58:53.340Z,1677013133.340 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-02-21T20:58:53.341Z,1677013133.341 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-02-21T20:58:53.342Z,1677013133.342 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2023-02-21T20:58:53.342Z,1677013133.342 [IBIT](INFO): Initialize IBIT Component.
2023-02-21T20:58:53.343Z,1677013133.343 [CBIT](DEBUG): Initialize CBIT Component.
2023-02-21T20:58:53.344Z,1677013133.344 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8843
2023-02-21T20:58:53.346Z,1677013133.346 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-02-21T20:58:53.351Z,1677013133.351 [Onboard ThreadHandler](INFO): Handler Thread ID is 8844
2023-02-21T20:58:53.352Z,1677013133.352 [logger ThreadHandler](INFO): Handler Thread ID is 8841
2023-02-21T20:58:53.356Z,1677013133.356 [CBIT](DEBUG): Initialized mux pins.
2023-02-21T20:58:53.357Z,1677013133.357 [CBIT](DEBUG): Initializing the watchdog timer.
2023-02-21T20:58:53.380Z,1677013133.380 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-02-21T20:58:53.380Z,1677013133.380 [CBIT](DEBUG): Initializing heartbeat.
2023-02-21T20:58:53.380Z,1677013133.380 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8845
2023-02-21T20:58:53.392Z,1677013133.392 [DAT ThreadHandler](INFO): Handler Thread ID is 8846
2023-02-21T20:58:53.393Z,1677013133.393 [DAT](INFO): Powering up
2023-02-21T20:58:53.393Z,1677013133.393 [DAT](DEBUG): Initializing DAT.
2023-02-21T20:58:53.397Z,1677013133.397 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8848
2023-02-21T20:58:53.400Z,1677013133.400 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-02-21T20:58:53.400Z,1677013133.400 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-02-21T20:58:53.400Z,1677013133.400 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-02-21T20:58:53.400Z,1677013133.400 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-02-21T20:58:53.400Z,1677013133.400 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-02-21T20:58:53.400Z,1677013133.400 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-02-21T20:58:53.401Z,1677013133.401 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-02-21T20:58:53.401Z,1677013133.401 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-02-21T20:58:53.401Z,1677013133.401 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2023-02-21T20:58:53.401Z,1677013133.401 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2023-02-21T20:58:53.452Z,1677013133.452 [CBIT](DEBUG): Deactivating GF circuits.
2023-02-21T20:58:53.452Z,1677013133.452 [CBIT](DEBUG): Deactivating emergency mode.
2023-02-21T20:58:53.488Z,1677013133.488 [CBIT](DEBUG): Backplane powered.
2023-02-21T20:58:53.492Z,1677013133.492 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-02-21T20:58:53.492Z,1677013133.492 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-02-21T20:58:53.493Z,1677013133.493 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-02-21T20:58:53.493Z,1677013133.493 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-02-21T20:58:53.493Z,1677013133.493 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-02-21T20:58:53.494Z,1677013133.494 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-02-21T20:58:53.495Z,1677013133.495 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-02-21T20:58:53.500Z,1677013133.500 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-02-21T20:58:53.502Z,1677013133.502 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-02-21T20:58:53.503Z,1677013133.503 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-02-21T20:58:53.508Z,1677013133.508 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-02-21T20:58:53.509Z,1677013133.509 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-02-21T20:58:53.546Z,1677013133.546 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-21T20:58:53.581Z,1677013133.581 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-02-21T20:58:53.582Z,1677013133.582 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-02-21T20:58:53.660Z,1677013133.660 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-02-21T20:58:53.661Z,1677013133.661 [Default:A.Wait](DEBUG): Construct Wait.
2023-02-21T20:58:53.664Z,1677013133.664 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-21T20:58:53.720Z,1677013133.720 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-02-21T20:58:53.723Z,1677013133.723 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-02-21T20:58:53.754Z,1677013133.754 [Default:E.Execute](DEBUG): Construct Execute.
2023-02-21T20:58:53.757Z,1677013133.757 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-02-21T20:58:53.767Z,1677013133.767 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-02-21T20:58:53.782Z,1677013133.782 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-02-21T20:58:53.784Z,1677013133.784 [Radio_Surface](INFO): Powering up
2023-02-21T20:58:53.860Z,1677013133.860 [Depth_Keller](ERROR): Pressure reading out of range: 1895.444336 decibar
2023-02-21T20:58:53.861Z,1677013133.861 [Power24vConverter](INFO): Powering up.
2023-02-21T20:58:53.912Z,1677013133.912 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-02-21T20:58:53.930Z,1677013133.930 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-02-21T20:58:53.936Z,1677013133.936 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-02-21T20:58:53.937Z,1677013133.937 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-02-21T20:58:53.948Z,1677013133.948 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-02-21T20:58:53.949Z,1677013133.949 [MassServo](DEBUG): Initializing EZServoServo.
2023-02-21T20:58:53.956Z,1677013133.956 [MassServo](DEBUG): Initializing MassServo.
2023-02-21T20:58:53.957Z,1677013133.957 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-21T20:58:53.964Z,1677013133.964 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-21T20:58:53.965Z,1677013133.965 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-02-21T20:58:53.972Z,1677013133.972 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-02-21T20:58:59.661Z,1677013139.661 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-02-21T20:59:04.992Z,1677013144.992 [DAT](INFO): DAT read: 

2023-02-21T20:59:04.994Z,1677013144.994 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-02-21T20:59:06.505Z,1677013146.505 [DAT](INFO): DAT read: MF Frequency Band

2023-02-21T20:59:06.506Z,1677013146.506 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-02-21T20:59:06.506Z,1677013146.506 [DAT](INFO): DAT read: Feb 21 2023 20:59:01 

2023-02-21T20:59:07.765Z,1677013147.765 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-02-21T20:59:07.766Z,1677013147.766 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2023-02-21T20:59:07.766Z,1677013147.766 [DAT](INFO): commRate: 1200
2023-02-21T20:59:09.832Z,1677013149.832 [DAT](INFO): entering command mode
2023-02-21T20:59:10.032Z,1677013150.032 [DAT](INFO): DAT read: 

2023-02-21T20:59:10.033Z,1677013150.033 [DAT](INFO): DAT read: user:1>

2023-02-21T20:59:10.033Z,1677013150.033 [DAT](INFO): setting verbose to 3
2023-02-21T20:59:10.284Z,1677013150.284 [DAT](INFO): DAT read: user:1>

2023-02-21T20:59:10.285Z,1677013150.285 [DAT](INFO): DAT read: Verbose         | 3           

2023-02-21T20:59:10.286Z,1677013150.286 [DAT](INFO): set verbose to 3
2023-02-21T20:59:10.286Z,1677013150.286 [DAT](INFO): setting DatVerbose to 27440
2023-02-21T20:59:10.536Z,1677013150.536 [DAT](INFO): DAT read: user:2>

2023-02-21T20:59:10.537Z,1677013150.537 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-02-21T20:59:10.538Z,1677013150.538 [DAT](INFO): set DatVerbose to 27440
2023-02-21T20:59:10.538Z,1677013150.538 [DAT](INFO): setting transmit power to 8
2023-02-21T20:59:10.790Z,1677013150.790 [DAT](INFO): DAT read: user:3>

2023-02-21T20:59:10.791Z,1677013150.791 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-02-21T20:59:10.791Z,1677013150.791 [DAT](INFO): set transmit power to 8
2023-02-21T20:59:10.791Z,1677013150.791 [DAT](INFO): setting local address to 4
2023-02-21T20:59:11.040Z,1677013151.040 [DAT](INFO): DAT read: user:4>

2023-02-21T20:59:11.041Z,1677013151.041 [DAT](INFO): DAT read: LocalAddr       | 4           

2023-02-21T20:59:11.041Z,1677013151.041 [DAT](INFO): set local address to 4
2023-02-21T20:59:11.042Z,1677013151.042 [DAT](INFO): Setting time to: 20:59:11 And date to:2/21/2023
2023-02-21T20:59:11.292Z,1677013151.292 [DAT](INFO): DAT read: user:5>

2023-02-21T20:59:11.293Z,1677013151.293 [DAT](INFO): DAT read: Tue Feb 21, 2023  20:59:11  

2023-02-21T20:59:11.294Z,1677013151.294 [DAT](INFO): Local DAT time set to Tue Feb 21, 2023  20:59:11  

2023-02-21T20:59:22.889Z,1677013162.889 [NAL9602](INFO): Powering up NAL9602
2023-02-21T20:59:33.798Z,1677013173.798 [NAL9602](INFO): NAL9602 initialized
2023-02-21T20:59:39.871Z,1677013179.871 [SBIT](IMPORTANT): Beginning Startup BIT
2023-02-21T20:59:39.875Z,1677013179.875 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-21T20:59:50.876Z,1677013190.876 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005399
CHAN A1 (24V): 0.001284
CHAN A2 (12V): -0.005229
CHAN A3 (5V): -0.003011
CHAN B0 (3.3V): -0.001263
CHAN B1 (3.15aV): -0.001393
CHAN B2 (3.15bV): -0.001023
CHAN B3 (GND): -0.000216
OPEN: 0.006698
Full Scale: +/- 1 mA
2023-02-21T20:59:53.278Z,1677013193.278 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.531549
2023-02-21T21:00:07.329Z,1677013207.329 [BPC1](ERROR): BPC1A: No match for serial number 04D7 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.331Z,1677013207.331 [BPC1](ERROR): BPC1A: No match for serial number 04CA in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.332Z,1677013207.332 [BPC1](ERROR): BPC1A: No match for serial number 04A1 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.333Z,1677013207.333 [BPC1](ERROR): BPC1A: No match for serial number 049B in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.334Z,1677013207.334 [BPC1](ERROR): BPC1A: No match for serial number 04BD in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.335Z,1677013207.335 [BPC1](ERROR): BPC1A: No match for serial number 0494 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.337Z,1677013207.337 [BPC1](ERROR): BPC1A: No match for serial number 0466 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.338Z,1677013207.338 [BPC1](ERROR): BPC1A: No match for serial number 0447 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-02-21T21:00:07.339Z,1677013207.339 [BPC1](ERROR): BPC1B: No match for serial number 0486 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.340Z,1677013207.340 [BPC1](ERROR): BPC1B: No match for serial number 0442 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.342Z,1677013207.342 [BPC1](ERROR): BPC1B: No match for serial number 0457 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.343Z,1677013207.343 [BPC1](ERROR): BPC1B: No match for serial number 0455 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.344Z,1677013207.344 [BPC1](ERROR): BPC1B: No match for serial number 04C0 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.345Z,1677013207.345 [BPC1](ERROR): BPC1B: No match for serial number 0459 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.346Z,1677013207.346 [BPC1](ERROR): BPC1B: No match for serial number 045F in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.347Z,1677013207.347 [BPC1](ERROR): BPC1B: No match for serial number 0449 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-02-21T21:00:07.348Z,1677013207.348 [BPC1](FAULT): Failed to parse data from all battery packs.
2023-02-21T21:00:07.348Z,1677013207.348 [BPC1] Data Fault, FailCount= 1
2023-02-21T21:00:07.348Z,1677013207.348 [BPC1](ERROR): Data Fault
2023-02-21T21:00:07.425Z,1677013207.425 [CBIT](ERROR): Data Fault in component: BPC1
2023-02-21T21:00:34.019Z,1677013234.019 [SBIT](CRITICAL): SBIT FAILED
2023-02-21T21:00:34.019Z,1677013234.019 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-02-21T21:00:34.020Z,1677013234.020 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2023-02-21T21:00:34.020Z,1677013234.020 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool;

2023-02-21T21:00:34.020Z,1677013234.020 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2023-02-21T21:00:34.020Z,1677013234.020 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): Depth_Keller.offset=-0.8 decibar;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=4 minute;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2023-02-21T21:00:34.021Z,1677013234.021 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff470nm;

2023-02-21T21:00:34.022Z,1677013234.022 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff650nm;

2023-02-21T21:00:34.022Z,1677013234.022 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=86.344810 cubic_centimeter;

2023-02-21T21:00:34.022Z,1677013234.022 [SBIT](IMPORTANT): VerticalControl.massDefault=-6.557592 millimeter;

2023-02-21T21:00:34.022Z,1677013234.022 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2023-02-21T21:00:34.024Z,1677013234.024 [CommandExec](FAULT): Scheduling is paused
2023-02-21T21:00:34.024Z,1677013234.024 [CBIT](INFO): Critical error at 20230221T210034
2023-02-21T21:00:34.024Z,1677013234.024 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-02-21T21:00:34.441Z,1677013234.441 [MissionManager](IMPORTANT): Started mission Startup
2023-02-21T21:00:34.442Z,1677013234.442 [Startup] Running Loop=1
2023-02-21T21:00:34.442Z,1677013234.442 [Startup](DEBUG): Aggregate::initialize Startup
2023-02-21T21:00:34.442Z,1677013234.442 [Startup:A.GoToSurface] Running Loop=1
2023-02-21T21:00:34.442Z,1677013234.442 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-21T21:00:34.443Z,1677013234.443 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-21T21:00:34.443Z,1677013234.443 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-21T21:00:34.443Z,1677013234.443 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-21T21:00:34.444Z,1677013234.444 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-21T21:00:34.444Z,1677013234.444 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-21T21:00:34.446Z,1677013234.446 [Startup:StartupSatComms] Running Loop=1
2023-02-21T21:00:34.446Z,1677013234.446 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-02-21T21:00:34.446Z,1677013234.446 [Startup:StartupSatComms:A] Running Loop=1
2023-02-21T21:00:34.830Z,1677013234.830 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-02-21T21:01:34.596Z,1677013294.596 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-21T21:00:34.4Z
2023-02-21T21:01:34.596Z,1677013294.596 [Startup:StartupSatComms:A] Stopped
2023-02-21T21:01:34.596Z,1677013294.596 [Startup:StartupSatComms:B] Running Loop=1
2023-02-21T21:01:35.008Z,1677013295.008 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-21T21:01:36.250Z,1677013296.250 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004838
2023-02-21T21:01:47.907Z,1677013307.907 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230221T205421/Courier0004.lzma
2023-02-21T21:01:48.910Z,1677013308.910 [DataOverHttps](INFO): Moved sent file to Logs/20230221T205421/Courier0004.lzma.bak
2023-02-21T21:01:48.910Z,1677013308.910 [DataOverHttps](INFO): SBD MOMSN=17484905
2023-02-21T21:01:53.628Z,1677013313.628 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-02-21T21:01:53.628Z,1677013313.628 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-21T21:01:53.669Z,1677013313.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-21T21:01:54.025Z,1677013314.025 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-21T21:01:54.025Z,1677013314.025 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-02-21T21:02:09.980Z,1677013329.980 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20230221T205843/Courier0000.lzma
2023-02-21T21:02:10.982Z,1677013330.982 [DataOverHttps](INFO): Moved sent file to Logs/20230221T205843/Courier0000.lzma.bak
2023-02-21T21:02:10.982Z,1677013330.982 [DataOverHttps](INFO): SBD MOMSN=17484907
2023-02-21T21:02:16.052Z,1677013336.052 [CommandExec](IMPORTANT): got command failComponent
2023-02-21T21:02:16.053Z,1677013336.053 [CommandExec](IMPORTANT): Failed components:
2023-02-21T21:02:16.053Z,1677013336.053 [CommandExec](IMPORTANT): BPC1: Data Fault
2023-02-21T21:02:34.995Z,1677013354.995 [Startup:StartupSatComms:B](INFO): Timed out from 2023-02-21T21:01:34.6Z
2023-02-21T21:02:34.995Z,1677013354.995 [Startup:StartupSatComms:B] Stopped
2023-02-21T21:02:34.995Z,1677013354.995 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-02-21T21:02:34.995Z,1677013354.995 [Startup:StartupSatComms] Stopped
2023-02-21T21:02:34.000Z,1677013355.000 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-02-21T21:02:35.001Z,1677013355.001 [Startup](INFO): Completed Startup
2023-02-21T21:02:35.001Z,1677013355.001 [MissionManager](INFO): Startup is completed.
2023-02-21T21:02:35.001Z,1677013355.001 [MissionManager](INFO): Uninitializing Mission Startup
2023-02-21T21:02:35.001Z,1677013355.001 [Startup] Stopped
2023-02-21T21:02:35.001Z,1677013355.001 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-02-21T21:02:35.001Z,1677013355.001 [Startup:A.GoToSurface] Stopped
2023-02-21T21:02:35.001Z,1677013355.001 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-21T21:02:35.568Z,1677013355.568 [MissionManager](IMPORTANT): Started mission Default
2023-02-21T21:02:35.568Z,1677013355.568 [Default] Running Loop=1
2023-02-21T21:02:35.569Z,1677013355.569 [Default](DEBUG): Aggregate::initialize Default
2023-02-21T21:02:35.569Z,1677013355.569 [Default:B.GoToSurface] Running Loop=1
2023-02-21T21:02:35.569Z,1677013355.569 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-21T21:02:35.569Z,1677013355.569 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-21T21:02:35.569Z,1677013355.569 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-21T21:02:35.570Z,1677013355.570 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-21T21:02:35.570Z,1677013355.570 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-21T21:02:35.570Z,1677013355.570 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-21T21:02:35.570Z,1677013355.570 [Default:A.Wait] Running Loop=1
2023-02-21T21:02:35.571Z,1677013355.571 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-02-21T21:02:48.790Z,1677013368.790 [Default:A.Wait](INFO): Done Waiting.
2023-02-21T21:02:48.790Z,1677013368.790 [Default:A.Wait] Stopped
2023-02-21T21:02:48.790Z,1677013368.790 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-02-21T21:02:49.137Z,1677013369.137 [Default:CheckIn] Running Loop=1
2023-02-21T21:02:49.138Z,1677013369.138 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-21T21:02:49.138Z,1677013369.138 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-21T21:02:49.565Z,1677013369.565 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-02-21T21:02:52.541Z,1677013372.541 [CommandExec](IMPORTANT): got command restart application
2023-02-21T21:02:53.544Z,1677013373.544 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:02:53.544Z,1677013373.544 [CommandExec](INFO): Uninitializing the command executive.
2023-02-21T21:02:53.544Z,1677013373.544 [CommandExec](INFO): Uninitializing the command scheduler.
2023-02-21T21:02:53.544Z,1677013373.544 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:53.620Z,1677013373.620 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-02-21T21:02:53.620Z,1677013373.620 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-02-21T21:02:53.620Z,1677013373.620 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:53.621Z,1677013373.621 [NavChartDb](INFO): Join timeout helper Thread ID is 8873
2023-02-21T21:02:53.640Z,1677013373.640 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:02:53.640Z,1677013373.640 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:53.641Z,1677013373.641 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-02-21T21:02:53.641Z,1677013373.641 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:53.641Z,1677013373.641 [DAT](INFO): Join timeout helper Thread ID is 8874
2023-02-21T21:02:53.749Z,1677013373.749 [DAT](INFO): Powering down
2023-02-21T21:02:53.817Z,1677013373.817 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:02:53.818Z,1677013373.818 [DAT](INFO): Powering down
2023-02-21T21:02:53.818Z,1677013373.818 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:53.825Z,1677013373.825 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-02-21T21:02:53.825Z,1677013373.825 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:53.825Z,1677013373.825 [Radio_Surface](INFO): Join timeout helper Thread ID is 8875
2023-02-21T21:02:54.032Z,1677013374.032 [Radio_Surface](INFO): Powering down
2023-02-21T21:02:54.033Z,1677013374.033 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:02:54.033Z,1677013374.033 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:54.052Z,1677013374.052 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-02-21T21:02:54.052Z,1677013374.052 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:54.052Z,1677013374.052 [Onboard](INFO): Join timeout helper Thread ID is 8876
2023-02-21T21:02:58.568Z,1677013378.568 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:02:58.568Z,1677013378.568 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:58.588Z,1677013378.588 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-02-21T21:02:58.588Z,1677013378.588 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:02:58.588Z,1677013378.588 [DataOverHttps](INFO): Join timeout helper Thread ID is 8877
2023-02-21T21:03:01.859Z,1677013381.859 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:03:01.863Z,1677013381.863 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.881Z,1677013381.881 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-02-21T21:03:01.881Z,1677013381.881 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.881Z,1677013381.881 [BackseatComponent](INFO): Join timeout helper Thread ID is 8878
2023-02-21T21:03:01.892Z,1677013381.892 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:03:01.892Z,1677013381.892 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.904Z,1677013381.904 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-02-21T21:03:01.904Z,1677013381.904 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.905Z,1677013381.905 [logger](INFO): Join timeout helper Thread ID is 8879
2023-02-21T21:03:01.929Z,1677013381.929 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:03:01.929Z,1677013381.929 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.949Z,1677013381.949 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-02-21T21:03:01.949Z,1677013381.949 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.949Z,1677013381.949 [CommandLine](INFO): Join timeout helper Thread ID is 8880
2023-02-21T21:03:01.952Z,1677013381.952 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:03:01.952Z,1677013381.952 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.973Z,1677013381.973 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-02-21T21:03:01.974Z,1677013381.974 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:01.976Z,1677013381.976 [CommandExec](INFO): Join timeout helper Thread ID is 8881
2023-02-21T21:03:02.008Z,1677013382.008 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-02-21T21:03:02.008Z,1677013382.008 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.008Z,1677013382.008 [controlThread](INFO): Join timeout helper Thread ID is 8882
2023-02-21T21:03:02.020Z,1677013382.020 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T21:03:02.020Z,1677013382.020 [controlThread](DEBUG): Uninitializing ControlThread
2023-02-21T21:03:02.021Z,1677013382.021 [AHRS_M2](INFO): Powering down
2023-02-21T21:03:02.093Z,1677013382.093 [NAL9602](INFO): Powering down
2023-02-21T21:03:02.094Z,1677013382.094 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-02-21T21:03:02.095Z,1677013382.095 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-02-21T21:03:02.101Z,1677013382.101 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-02-21T21:03:02.102Z,1677013382.102 [MissionManager](INFO): Uninitializing Mission Default
2023-02-21T21:03:02.102Z,1677013382.102 [Default] Stopped
2023-02-21T21:03:02.102Z,1677013382.102 [Default](DEBUG): Aggregate::uninitialize Default
2023-02-21T21:03:02.102Z,1677013382.102 [Default:B.GoToSurface] Stopped
2023-02-21T21:03:02.102Z,1677013382.102 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-21T21:03:02.102Z,1677013382.102 [Default:CheckIn] Stopped
2023-02-21T21:03:02.103Z,1677013382.103 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-21T21:03:02.103Z,1677013382.103 [Default:CheckIn:Read_GPS] Stopped
2023-02-21T21:03:02.114Z,1677013382.114 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-02-21T21:03:02.114Z,1677013382.114 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-02-21T21:03:02.114Z,1677013382.114 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-02-21T21:03:02.114Z,1677013382.114 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-02-21T21:03:02.115Z,1677013382.115 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-02-21T21:03:02.115Z,1677013382.115 [BuoyancyServo](INFO): Powering down
2023-02-21T21:03:02.132Z,1677013382.132 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-02-21T21:03:02.132Z,1677013382.132 [ElevatorServo](INFO): Powering down
2023-02-21T21:03:02.133Z,1677013382.133 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-02-21T21:03:02.133Z,1677013382.133 [MassServo](INFO): Powering down
2023-02-21T21:03:02.134Z,1677013382.134 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-21T21:03:02.134Z,1677013382.134 [RudderServo](INFO): Powering down
2023-02-21T21:03:02.135Z,1677013382.135 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-02-21T21:03:02.135Z,1677013382.135 [ThrusterHE](INFO): Powering down
2023-02-21T21:03:02.139Z,1677013382.139 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-02-21T21:03:02.139Z,1677013382.139 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-02-21T21:03:02.139Z,1677013382.139 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-02-21T21:03:02.141Z,1677013382.141 [CBIT](DEBUG): Powering off loads.
2023-02-21T21:03:02.152Z,1677013382.152 [CBIT](DEBUG): Disabling WDT.
2023-02-21T21:03:02.164Z,1677013382.164 [CBIT](DEBUG): Opening all GF detection circuits.
2023-02-21T21:03:02.165Z,1677013382.165 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.435Z,1677013382.435 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.444Z,1677013382.444 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.458Z,1677013382.458 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.474Z,1677013382.474 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.674Z,1677013382.674 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.842Z,1677013382.842 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-21T21:03:02.997Z,1677013382.997 [logger ThreadHandler](INFO): Thread cancelled.