2023-08-23T00:13:38.914Z,1692749618.914 [Supervisor](DEBUG): Initializing supervisor.
2023-08-23T00:13:38.919Z,1692749618.919 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-23T00:13:38.919Z,1692749618.919 [SyncHandler](INFO): Protected caller Thread ID is 826
2023-08-23T00:13:38.920Z,1692749618.920 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-23T00:13:38.921Z,1692749618.921 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-23T00:13:38.922Z,1692749618.922 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 827
2023-08-23T00:13:38.932Z,1692749618.932 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-23T00:13:38.956Z,1692749618.956 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-23T00:13:38.957Z,1692749618.957 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-23T00:13:38.958Z,1692749618.958 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 828
2023-08-23T00:13:38.962Z,1692749618.962 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-23T00:13:38.964Z,1692749618.964 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-23T00:13:38.964Z,1692749618.964 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 829
2023-08-23T00:13:38.968Z,1692749618.968 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-23T00:13:38.969Z,1692749618.969 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-23T00:13:38.970Z,1692749618.970 [logger ThreadHandler](INFO): Protected caller Thread ID is 830
2023-08-23T00:13:38.979Z,1692749618.979 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-23T00:13:38.980Z,1692749618.980 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-23T00:13:38.984Z,1692749618.984 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-23T00:13:39.359Z,1692749619.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-23T00:13:39.360Z,1692749619.360 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-23T00:13:39.439Z,1692749619.439 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-23T00:13:39.544Z,1692749619.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-23T00:13:39.546Z,1692749619.546 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-23T00:13:39.671Z,1692749619.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-23T00:13:39.671Z,1692749619.671 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-23T00:13:39.998Z,1692749619.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-23T00:13:39.999Z,1692749619.999 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-23T00:13:40.093Z,1692749620.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-23T00:13:40.094Z,1692749620.094 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-23T00:13:40.483Z,1692749620.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-23T00:13:40.485Z,1692749620.485 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-23T00:13:40.701Z,1692749620.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-23T00:13:40.701Z,1692749620.701 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-23T00:13:40.780Z,1692749620.780 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-23T00:13:40.995Z,1692749620.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-23T00:13:40.996Z,1692749620.996 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-23T00:13:41.212Z,1692749621.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-23T00:13:41.212Z,1692749621.212 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-23T00:13:41.698Z,1692749621.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-23T00:13:41.698Z,1692749621.698 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-23T00:13:41.877Z,1692749621.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-23T00:13:41.878Z,1692749621.878 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-23T00:13:42.148Z,1692749622.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-23T00:13:42.149Z,1692749622.149 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-23T00:13:42.938Z,1692749622.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-23T00:13:42.939Z,1692749622.939 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-23T00:13:43.435Z,1692749623.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-23T00:13:43.437Z,1692749623.437 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2023-08-23T00:13:43.439Z,1692749623.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2023-08-23T00:13:43.534Z,1692749623.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2023-08-23T00:13:43.650Z,1692749623.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2023-08-23T00:13:43.733Z,1692749623.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2023-08-23T00:13:43.839Z,1692749623.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2023-08-23T00:13:43.937Z,1692749623.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2023-08-23T00:13:44.195Z,1692749624.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-23T00:13:44.196Z,1692749624.196 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2023-08-23T00:13:44.299Z,1692749624.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2023-08-23T00:13:44.392Z,1692749624.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2023-08-23T00:13:44.494Z,1692749624.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2023-08-23T00:13:44.613Z,1692749624.613 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2023-08-23T00:13:44.698Z,1692749624.698 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-08-23T00:13:44.702Z,1692749624.702 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-23T00:13:44.827Z,1692749624.827 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-23T00:13:44.827Z,1692749624.827 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-23T00:13:44.893Z,1692749624.893 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-23T00:13:44.953Z,1692749624.953 [VerticalControl] Loaded
2023-08-23T00:13:44.953Z,1692749624.953 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-23T00:13:44.956Z,1692749624.956 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-23T00:13:44.997Z,1692749624.997 [HorizontalControl] Loaded
2023-08-23T00:13:44.997Z,1692749624.997 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-23T00:13:44.000Z,1692749625.000 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-23T00:13:45.003Z,1692749625.003 [SpeedControl] Loaded
2023-08-23T00:13:45.003Z,1692749625.003 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-23T00:13:45.006Z,1692749625.006 [LoopControl](DEBUG): Construct LoopControl.
2023-08-23T00:13:45.006Z,1692749625.006 [LoopControl] Loaded
2023-08-23T00:13:45.006Z,1692749625.006 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-23T00:13:45.007Z,1692749625.007 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-23T00:13:45.008Z,1692749625.008 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-23T00:13:45.089Z,1692749625.089 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-23T00:13:45.090Z,1692749625.090 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-23T00:13:45.887Z,1692749625.887 [AHRS_M2] Loaded
2023-08-23T00:13:45.887Z,1692749625.887 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-23T00:13:46.705Z,1692749626.705 [BPC1] Loaded
2023-08-23T00:13:46.706Z,1692749626.706 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-23T00:13:46.846Z,1692749626.846 [DataOverHttps] Loaded
2023-08-23T00:13:46.847Z,1692749626.847 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-23T00:13:46.848Z,1692749626.848 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087F4E0
2023-08-23T00:13:46.848Z,1692749626.848 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 912
2023-08-23T00:13:46.894Z,1692749626.894 [Depth_Keller] Loaded
2023-08-23T00:13:46.894Z,1692749626.894 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-23T00:13:46.899Z,1692749626.899 [DropWeight] Loaded
2023-08-23T00:13:46.899Z,1692749626.899 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-23T00:13:46.961Z,1692749626.961 [NAL9602] Loaded
2023-08-23T00:13:46.962Z,1692749626.962 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-23T00:13:46.991Z,1692749626.991 [Onboard] Loaded
2023-08-23T00:13:46.992Z,1692749626.992 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-23T00:13:46.993Z,1692749626.993 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AF4E0
2023-08-23T00:13:46.993Z,1692749626.993 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 913
2023-08-23T00:13:47.006Z,1692749627.006 [Power24vConverter] Loaded
2023-08-23T00:13:47.006Z,1692749627.006 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-23T00:13:47.020Z,1692749627.020 [Radio_Surface] Loaded
2023-08-23T00:13:47.020Z,1692749627.020 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-23T00:13:47.021Z,1692749627.021 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DF4E0
2023-08-23T00:13:47.021Z,1692749627.021 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 914
2023-08-23T00:13:47.054Z,1692749627.054 [Rowe_600] Loaded
2023-08-23T00:13:47.054Z,1692749627.054 [ComponentRegistry](DEBUG): SyncComponent "Rowe_600" handled in the control thread.
2023-08-23T00:13:47.147Z,1692749627.147 [DAT] Loaded
2023-08-23T00:13:47.147Z,1692749627.147 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-08-23T00:13:47.148Z,1692749627.148 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0
2023-08-23T00:13:47.148Z,1692749627.148 [DAT ThreadHandler](INFO): Protected caller Thread ID is 915
2023-08-23T00:13:47.149Z,1692749627.149 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-23T00:13:47.149Z,1692749627.149 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-23T00:13:47.165Z,1692749627.165 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-23T00:13:47.165Z,1692749627.165 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-23T00:13:47.206Z,1692749627.206 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-23T00:13:47.208Z,1692749627.208 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-23T00:13:47.375Z,1692749627.375 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-23T00:13:47.384Z,1692749627.384 [SBIT] Loaded
2023-08-23T00:13:47.384Z,1692749627.384 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-23T00:13:47.387Z,1692749627.387 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-23T00:13:47.400Z,1692749627.400 [IBIT] Loaded
2023-08-23T00:13:47.400Z,1692749627.400 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-23T00:13:47.406Z,1692749627.406 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-23T00:13:47.510Z,1692749627.510 [CBIT] Loaded
2023-08-23T00:13:47.511Z,1692749627.511 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-23T00:13:47.511Z,1692749627.511 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-23T00:13:47.512Z,1692749627.512 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-23T00:13:47.867Z,1692749627.867 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-23T00:13:47.867Z,1692749627.867 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-23T00:13:47.935Z,1692749627.935 [DepthRateCalculator] Loaded
2023-08-23T00:13:47.936Z,1692749627.936 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-23T00:13:47.941Z,1692749627.941 [PitchRateCalculator] Loaded
2023-08-23T00:13:47.941Z,1692749627.941 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-23T00:13:47.951Z,1692749627.951 [SpeedCalculator] Loaded
2023-08-23T00:13:47.951Z,1692749627.951 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-23T00:13:47.956Z,1692749627.956 [YawRateCalculator] Loaded
2023-08-23T00:13:47.956Z,1692749627.956 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-23T00:13:47.974Z,1692749627.974 [ElevatorOffsetCalculator] Loaded
2023-08-23T00:13:47.975Z,1692749627.975 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-23T00:13:47.975Z,1692749627.975 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-23T00:13:47.976Z,1692749627.976 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-23T00:13:48.157Z,1692749628.157 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-23T00:13:48.158Z,1692749628.158 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-23T00:13:48.292Z,1692749628.292 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-08-23T00:13:48.293Z,1692749628.293 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-08-23T00:13:48.336Z,1692749628.336 [DeadReckonUsingSpeedCalculator] Loaded
2023-08-23T00:13:48.336Z,1692749628.336 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-08-23T00:13:48.379Z,1692749628.379 [DeadReckonWithRespectToSeafloor] Loaded
2023-08-23T00:13:48.379Z,1692749628.379 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2023-08-23T00:13:48.393Z,1692749628.393 [NavChart] Loaded
2023-08-23T00:13:48.393Z,1692749628.393 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-23T00:13:48.399Z,1692749628.399 [UniversalFixResidualReporter] Loaded
2023-08-23T00:13:48.399Z,1692749628.399 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-23T00:13:48.399Z,1692749628.399 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-23T00:13:48.400Z,1692749628.400 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-23T00:13:48.556Z,1692749628.556 [BuoyancyServo] Loaded
2023-08-23T00:13:48.556Z,1692749628.556 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-23T00:13:48.577Z,1692749628.577 [ElevatorServo] Loaded
2023-08-23T00:13:48.577Z,1692749628.577 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-23T00:13:48.597Z,1692749628.597 [MassServo] Loaded
2023-08-23T00:13:48.597Z,1692749628.597 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-23T00:13:48.615Z,1692749628.615 [RudderServo] Loaded
2023-08-23T00:13:48.616Z,1692749628.616 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-23T00:13:48.630Z,1692749628.630 [ThrusterHE] Loaded
2023-08-23T00:13:48.631Z,1692749628.631 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-23T00:13:48.631Z,1692749628.631 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-23T00:13:48.632Z,1692749628.632 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-23T00:13:48.853Z,1692749628.853 [CTD_Seabird] Loaded
2023-08-23T00:13:48.853Z,1692749628.853 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-23T00:13:48.854Z,1692749628.854 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B694E0
2023-08-23T00:13:48.855Z,1692749628.855 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 916
2023-08-23T00:13:48.888Z,1692749628.888 [ESPComponent] Loaded
2023-08-23T00:13:48.889Z,1692749628.889 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-08-23T00:13:48.909Z,1692749628.909 [PAR_Licor] Loaded
2023-08-23T00:13:48.909Z,1692749628.909 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-23T00:13:48.949Z,1692749628.949 [WetLabsBB2FL] Loaded
2023-08-23T00:13:48.950Z,1692749628.950 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-23T00:13:48.951Z,1692749628.951 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B994E0
2023-08-23T00:13:48.951Z,1692749628.951 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 917
2023-08-23T00:13:48.952Z,1692749628.952 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-23T00:13:48.958Z,1692749628.958 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-23T00:13:48.961Z,1692749628.961 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-23T00:13:48.972Z,1692749628.972 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-23T00:13:48.973Z,1692749628.973 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC94E0
2023-08-23T00:13:48.973Z,1692749628.973 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 918
2023-08-23T00:13:48.978Z,1692749628.978 [Supervisor](INFO): Main Thread ID is 818
2023-08-23T00:13:48.978Z,1692749628.978 [Supervisor](DEBUG): Running supervisor.
2023-08-23T00:13:48.978Z,1692749628.978 [CommandExec ThreadHandler](INFO): Handler Thread ID is 919
2023-08-23T00:13:48.979Z,1692749628.979 [CommandExec](INFO): Initializing the command executive.
2023-08-23T00:13:48.980Z,1692749628.980 [CommandLine ThreadHandler](INFO): Handler Thread ID is 920
2023-08-23T00:13:48.983Z,1692749628.983 [controlThread ThreadHandler](INFO): Handler Thread ID is 921
2023-08-23T00:13:48.983Z,1692749628.983 [controlThread](DEBUG): Initializing ControlThread
2023-08-23T00:13:48.984Z,1692749628.984 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-23T00:13:48.986Z,1692749628.986 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-23T00:13:48.986Z,1692749628.986 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-23T00:13:48.987Z,1692749628.987 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-23T00:13:48.993Z,1692749628.993 [SBIT](INFO): Initialize SBIT Component.
2023-08-23T00:13:48.993Z,1692749628.993 [SBIT](IMPORTANT): git: 2023-08-02
2023-08-23T00:13:48.994Z,1692749628.994 [SBIT](INFO): git hash: 04bff54855c04b79675eb3036915d66bd94ee3d9
2023-08-23T00:13:48.994Z,1692749628.994 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-23T00:13:48.995Z,1692749628.995 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-08-23T00:13:48.996Z,1692749628.996 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2023-08-23T00:13:48.997Z,1692749628.997 [IBIT](INFO): Initialize IBIT Component.
2023-08-23T00:13:48.998Z,1692749628.998 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-23T00:13:48.999Z,1692749628.999 [logger ThreadHandler](INFO): Handler Thread ID is 922
2023-08-23T00:13:49.011Z,1692749629.011 [CBIT](DEBUG): Initialized mux pins.
2023-08-23T00:13:49.011Z,1692749629.011 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2023-08-23T00:13:49.011Z,1692749629.011 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-23T00:13:49.015Z,1692749629.015 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 923
2023-08-23T00:13:49.016Z,1692749629.016 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-23T00:13:49.024Z,1692749629.024 [Onboard ThreadHandler](INFO): Handler Thread ID is 924
2023-08-23T00:13:49.036Z,1692749629.036 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-23T00:13:49.036Z,1692749629.036 [CBIT](DEBUG): Initializing heartbeat.
2023-08-23T00:13:49.046Z,1692749629.046 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 925
2023-08-23T00:13:49.059Z,1692749629.059 [DAT ThreadHandler](INFO): Handler Thread ID is 926
2023-08-23T00:13:49.060Z,1692749629.060 [DAT](INFO): Powering up
2023-08-23T00:13:49.060Z,1692749629.060 [DAT](DEBUG): Initializing DAT.
2023-08-23T00:13:49.064Z,1692749629.064 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 928
2023-08-23T00:13:49.065Z,1692749629.065 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-23T00:13:49.068Z,1692749629.068 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 930
2023-08-23T00:13:49.072Z,1692749629.072 [WetLabsBB2FL](INFO): Powering up
2023-08-23T00:13:49.073Z,1692749629.073 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 932
2023-08-23T00:13:49.080Z,1692749629.080 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2023-08-23T00:13:49.080Z,1692749629.080 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2023-08-23T00:13:49.080Z,1692749629.080 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2023-08-23T00:13:49.081Z,1692749629.081 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2023-08-23T00:13:49.081Z,1692749629.081 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-08-23T00:13:49.081Z,1692749629.081 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-08-23T00:13:49.081Z,1692749629.081 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-08-23T00:13:49.081Z,1692749629.081 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-08-23T00:13:49.082Z,1692749629.082 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2023-08-23T00:13:49.082Z,1692749629.082 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2023-08-23T00:13:49.082Z,1692749629.082 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2023-08-23T00:13:49.082Z,1692749629.082 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2023-08-23T00:13:49.107Z,1692749629.107 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-23T00:13:49.107Z,1692749629.107 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-23T00:13:49.147Z,1692749629.147 [CBIT](DEBUG): Backplane powered.
2023-08-23T00:13:49.147Z,1692749629.147 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-23T00:13:49.148Z,1692749629.148 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-23T00:13:49.148Z,1692749629.148 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-23T00:13:49.148Z,1692749629.148 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-23T00:13:49.148Z,1692749629.148 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-23T00:13:49.150Z,1692749629.150 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T00:13:49.150Z,1692749629.150 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-23T00:13:49.151Z,1692749629.151 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-23T00:13:49.156Z,1692749629.156 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-23T00:13:49.184Z,1692749629.184 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-23T00:13:49.223Z,1692749629.223 [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-08-23T00:13:49.224Z,1692749629.224 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-23T00:13:49.283Z,1692749629.283 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-23T00:13:49.285Z,1692749629.285 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-23T00:13:49.287Z,1692749629.287 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-23T00:13:49.320Z,1692749629.320 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-23T00:13:49.335Z,1692749629.335 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-23T00:13:49.341Z,1692749629.341 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-23T00:13:49.344Z,1692749629.344 [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-08-23T00:13:49.359Z,1692749629.359 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Rowe_600,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-08-23T00:13:49.365Z,1692749629.365 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-23T00:13:49.451Z,1692749629.451 [Radio_Surface](INFO): Powering up
2023-08-23T00:13:49.526Z,1692749629.526 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2023-08-23T00:13:49.527Z,1692749629.527 [Power24vConverter](INFO): Powering up.
2023-08-23T00:13:49.541Z,1692749629.541 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-08-23T00:13:49.575Z,1692749629.575 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-23T00:13:49.583Z,1692749629.583 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-23T00:13:49.584Z,1692749629.584 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-23T00:13:49.592Z,1692749629.592 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-23T00:13:49.593Z,1692749629.593 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-23T00:13:49.599Z,1692749629.599 [MassServo](DEBUG): Initializing MassServo.
2023-08-23T00:13:49.600Z,1692749629.600 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-23T00:13:49.607Z,1692749629.607 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-23T00:13:49.608Z,1692749629.608 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-23T00:13:49.615Z,1692749629.615 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-23T00:13:49.817Z,1692749629.817 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-08-23T00:13:49.817Z,1692749629.817 [DropWeight] Hardware Fault, FailCount= 1
2023-08-23T00:13:49.817Z,1692749629.817 [DropWeight](ERROR): Hardware Fault
2023-08-23T00:13:49.843Z,1692749629.843 [CommandExec](FAULT): Scheduling is paused
2023-08-23T00:13:49.843Z,1692749629.843 [CBIT](INFO): Critical error at 20230823T001349
2023-08-23T00:13:49.844Z,1692749629.844 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-08-23T00:13:49.846Z,1692749629.846 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-08-23T00:13:49.847Z,1692749629.847 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-08-23T00:13:50.195Z,1692749630.195 [CBIT](INFO): Critical error at 20230823T001349
2023-08-23T00:13:51.179Z,1692749631.179 [WetLabsBB2FL](INFO): Powering down
2023-08-23T00:13:52.598Z,1692749632.598 [AHRS_M2](DEBUG): boresightMatrix m[ decimal 0 0 2 2 f0.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f0.0 ]m set drop

2023-08-23T00:13:53.000Z,1692749633.000 [AHRS_M2](DEBUG): chan0Format 2 set drop

2023-08-23T00:13:53.404Z,1692749633.404 [AHRS_M2](DEBUG): chan0Trigger 5 set drop

2023-08-23T00:13:53.808Z,1692749633.808 [AHRS_M2](DEBUG): chan0Enables array[ 0 15 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 ]array set drop

2023-08-23T00:13:53.808Z,1692749633.808 [AHRS_M2](DEBUG): chan0EnableBit pitch dvid@ set drop

2023-08-23T00:13:54.212Z,1692749634.212 [AHRS_M2](DEBUG): chan0EnableBit roll dvid@ set drop

2023-08-23T00:13:54.616Z,1692749634.616 [AHRS_M2](DEBUG): chan0EnableBit yaw dvid@ set drop

2023-08-23T00:13:55.021Z,1692749635.021 [AHRS_M2](DEBUG): chan0EnableBit magp dvid@ set drop

2023-08-23T00:13:55.268Z,1692749635.268 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-23T00:13:55.424Z,1692749635.424 [AHRS_M2](DEBUG): chan0EnableBit accelp dvid@ set drop

2023-08-23T00:13:55.828Z,1692749635.828 [AHRS_M2](DEBUG): chan0EnableBit gyrop dvid@ set drop

2023-08-23T00:13:56.232Z,1692749636.232 [AHRS_M2](DEBUG): chan0EnableBit yawErrEst dvid@ set drop

2023-08-23T00:13:56.636Z,1692749636.636 [AHRS_M2](DEBUG): chan0EnableBit temperature dvid@ set drop

2023-08-23T00:13:57.040Z,1692749637.040 [AHRS_M2](DEBUG): chan0EnableBit magBufferActiveIndex dvid@ set drop

2023-08-23T00:13:57.444Z,1692749637.444 [AHRS_M2](DEBUG): chan0TriggerDivisor 40 set drop

2023-08-23T00:13:59.113Z,1692749639.113 [CBIT](CRITICAL): Environmental Failure. Press:14.743063 PSI. Humidity:56%. Temp:25 C. ABORTING MISSION
2023-08-23T00:13:59.521Z,1692749639.521 [CBIT](INFO): Critical error at 20230823T001359
2023-08-23T00:14:07.544Z,1692749647.544 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-08-23T00:14:07.544Z,1692749647.544 [AHRS_M2] Data Fault, FailCount= 1
2023-08-23T00:14:07.544Z,1692749647.544 [AHRS_M2](ERROR): Data Fault
2023-08-23T00:14:07.613Z,1692749647.613 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-08-23T00:14:07.614Z,1692749647.614 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2023-08-23T00:14:07.993Z,1692749647.993 [CBIT](INFO): Critical error at 20230823T001407
2023-08-23T00:14:11.997Z,1692749651.997 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-23T00:14:11.997Z,1692749651.997 [Rowe_600] Communications Fault, FailCount= 1
2023-08-23T00:14:11.997Z,1692749651.997 [Rowe_600](ERROR): Communications Fault
2023-08-23T00:14:12.048Z,1692749652.048 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-23T00:14:12.467Z,1692749652.467 [Rowe_600](INFO): Powering down
2023-08-23T00:14:13.255Z,1692749653.255 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-23T00:14:13.255Z,1692749653.255 [Rowe_600] No Fault, FailCount= 1
2023-08-23T00:14:17.248Z,1692749657.248 [NAL9602](INFO): Powering up NAL9602
2023-08-23T00:14:28.157Z,1692749668.157 [NAL9602](INFO): NAL9602 initialized
2023-08-23T00:14:35.834Z,1692749675.834 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-23T00:14:35.834Z,1692749675.834 [Rowe_600] Communications Fault, FailCount= 2
2023-08-23T00:14:35.834Z,1692749675.834 [Rowe_600](ERROR): Communications Fault
2023-08-23T00:14:35.850Z,1692749675.850 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-23T00:14:36.319Z,1692749676.319 [Rowe_600](INFO): Powering down
2023-08-23T00:14:37.081Z,1692749677.081 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-23T00:14:37.081Z,1692749677.081 [Rowe_600] No Fault, FailCount= 2
2023-08-23T00:14:49.073Z,1692749689.073 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-23T00:14:49.073Z,1692749689.073 [DAT] Communications Fault, FailCount= 1
2023-08-23T00:14:49.073Z,1692749689.073 [DAT](ERROR): Communications Fault
2023-08-23T00:14:49.179Z,1692749689.179 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-23T00:14:49.475Z,1692749689.475 [DAT](INFO): Powering down
2023-08-23T00:14:50.405Z,1692749690.405 [CBIT](INFO): Clearing failed state for component DAT
2023-08-23T00:14:50.405Z,1692749690.405 [DAT] No Fault, FailCount= 1
2023-08-23T00:14:52.523Z,1692749692.523 [DAT](INFO): Powering up
2023-08-23T00:14:52.523Z,1692749692.523 [DAT](DEBUG): Initializing DAT.
2023-08-23T00:14:58.449Z,1692749698.449 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:14:59.669Z,1692749699.669 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-23T00:14:59.669Z,1692749699.669 [Rowe_600] Communications Fault, FailCount= 3
2023-08-23T00:14:59.669Z,1692749699.669 [Rowe_600](ERROR): Communications Fault
2023-08-23T00:14:59.686Z,1692749699.686 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-23T00:14:59.687Z,1692749699.687 [CBIT](CRITICAL): Communications Fault in component: Rowe_600
2023-08-23T00:15:00.139Z,1692749700.139 [Rowe_600](INFO): Powering down
2023-08-23T00:15:00.173Z,1692749700.173 [CBIT](INFO): Critical error at 20230823T001459
2023-08-23T00:15:00.490Z,1692749700.490 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-23T00:15:00.495Z,1692749700.495 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-23T00:15:11.472Z,1692749711.472 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.038622
CHAN A1 (24V): 0.051856
CHAN A2 (12V): -0.000347
CHAN A3 (5V): -0.001450
CHAN B0 (3.3V): 0.000104
CHAN B1 (3.15aV): 0.000320
CHAN B2 (3.15bV): -0.000078
CHAN B3 (GND): -0.000451
OPEN: 0.006585
Full Scale: +/- 1 mA
2023-08-23T00:15:13.397Z,1692749713.397 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:15:28.749Z,1692749728.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:15:43.707Z,1692749743.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:15:49.366Z,1692749749.366 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:15:49.366Z,1692749749.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-08-23T00:15:49.366Z,1692749749.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T00:15:49.368Z,1692749749.368 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:15:49.368Z,1692749749.368 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-08-23T00:15:49.368Z,1692749749.368 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T00:15:49.370Z,1692749749.370 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:15:49.370Z,1692749749.370 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2023-08-23T00:15:49.370Z,1692749749.370 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-08-23T00:15:49.425Z,1692749749.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T00:15:49.425Z,1692749749.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T00:15:49.425Z,1692749749.425 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-08-23T00:15:49.804Z,1692749749.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T00:15:49.804Z,1692749749.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-08-23T00:15:49.805Z,1692749749.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T00:15:49.805Z,1692749749.805 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-08-23T00:15:49.805Z,1692749749.805 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-08-23T00:15:49.805Z,1692749749.805 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2023-08-23T00:15:50.187Z,1692749750.187 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T00:15:52.532Z,1692749752.532 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-23T00:15:52.532Z,1692749752.532 [DAT] Communications Fault, FailCount= 2
2023-08-23T00:15:52.532Z,1692749752.532 [DAT](ERROR): Communications Fault
2023-08-23T00:15:52.616Z,1692749752.616 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-23T00:15:52.935Z,1692749752.935 [DAT](INFO): Powering down
2023-08-23T00:15:53.821Z,1692749753.821 [CBIT](INFO): Clearing failed state for component DAT
2023-08-23T00:15:53.821Z,1692749753.821 [DAT] No Fault, FailCount= 2
2023-08-23T00:15:54.222Z,1692749754.222 [SBIT](IMPORTANT): SBIT PASSED
2023-08-23T00:15:54.222Z,1692749754.222 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-08-23T00:15:54.624Z,1692749754.624 [MissionManager](IMPORTANT): Started mission Startup
2023-08-23T00:15:54.625Z,1692749754.625 [Startup] Running Loop=1
2023-08-23T00:15:54.625Z,1692749754.625 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-23T00:15:54.625Z,1692749754.625 [Startup:A.GoToSurface] Running Loop=1
2023-08-23T00:15:54.625Z,1692749754.625 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-23T00:15:54.626Z,1692749754.626 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-23T00:15:54.626Z,1692749754.626 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-23T00:15:54.626Z,1692749754.626 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-23T00:15:54.627Z,1692749754.627 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-23T00:15:54.627Z,1692749754.627 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-23T00:15:54.629Z,1692749754.629 [Startup:StartupSatComms] Running Loop=1
2023-08-23T00:15:54.629Z,1692749754.629 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-23T00:15:54.629Z,1692749754.629 [Startup:StartupSatComms:A] Running Loop=1
2023-08-23T00:15:55.046Z,1692749755.046 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-23T00:15:55.983Z,1692749755.983 [DAT](INFO): Powering up
2023-08-23T00:15:55.983Z,1692749755.983 [DAT](DEBUG): Initializing DAT.
2023-08-23T00:15:58.645Z,1692749758.645 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:16:13.592Z,1692749773.592 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:16:20.670Z,1692749780.670 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004974
2023-08-23T00:16:28.945Z,1692749788.945 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:16:43.892Z,1692749803.892 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:16:54.817Z,1692749814.817 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-23T00:15:54.6Z
2023-08-23T00:16:54.817Z,1692749814.817 [Startup:StartupSatComms:A] Stopped
2023-08-23T00:16:54.817Z,1692749814.817 [Startup:StartupSatComms:B] Running Loop=1
2023-08-23T00:16:55.222Z,1692749815.222 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-23T00:16:56.012Z,1692749816.012 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-23T00:16:56.012Z,1692749816.012 [DAT] Communications Fault, FailCount= 3
2023-08-23T00:16:56.012Z,1692749816.012 [DAT](ERROR): Communications Fault
2023-08-23T00:16:56.066Z,1692749816.066 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-23T00:16:56.415Z,1692749816.415 [DAT](INFO): Powering down
2023-08-23T00:16:57.654Z,1692749817.654 [CBIT](INFO): Clearing failed state for component DAT
2023-08-23T00:16:57.654Z,1692749817.654 [DAT] No Fault, FailCount= 3
2023-08-23T00:16:59.244Z,1692749819.244 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:16:59.467Z,1692749819.467 [DAT](INFO): Powering up
2023-08-23T00:16:59.467Z,1692749819.467 [DAT](DEBUG): Initializing DAT.
2023-08-23T00:17:02.012Z,1692749822.012 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20230822T234331/Courier0004.lzma
2023-08-23T00:17:03.013Z,1692749823.013 [DataOverHttps](INFO): Moved sent file to Logs/20230822T234331/Courier0004.lzma.bak
2023-08-23T00:17:03.013Z,1692749823.013 [DataOverHttps](INFO): SBD MOMSN=18581746
2023-08-23T00:17:13.788Z,1692749833.788 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-23T00:17:18.257Z,1692749838.257 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20230823T000345/Courier0000.lzma
2023-08-23T00:17:19.257Z,1692749839.257 [DataOverHttps](INFO): Moved sent file to Logs/20230823T000345/Courier0000.lzma.bak
2023-08-23T00:17:19.257Z,1692749839.257 [DataOverHttps](INFO): SBD MOMSN=18581753
2023-08-23T00:17:34.523Z,1692749854.523 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230823T000946/Courier0000.lzma
2023-08-23T00:17:35.525Z,1692749855.525 [DataOverHttps](INFO): Moved sent file to Logs/20230823T000946/Courier0000.lzma.bak
2023-08-23T00:17:35.525Z,1692749855.525 [DataOverHttps](INFO): SBD MOMSN=18581802
2023-08-23T00:17:50.191Z,1692749870.191 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:17:50.192Z,1692749870.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-08-23T00:17:50.192Z,1692749870.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T00:17:50.193Z,1692749870.193 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:17:50.193Z,1692749870.193 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2023-08-23T00:17:50.193Z,1692749870.193 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T00:17:50.195Z,1692749870.195 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:17:50.195Z,1692749870.195 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2023-08-23T00:17:50.195Z,1692749870.195 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-08-23T00:17:50.206Z,1692749870.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T00:17:50.206Z,1692749870.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T00:17:50.206Z,1692749870.206 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-08-23T00:17:50.622Z,1692749870.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T00:17:50.622Z,1692749870.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-08-23T00:17:50.623Z,1692749870.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T00:17:50.623Z,1692749870.623 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2023-08-23T00:17:50.624Z,1692749870.624 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-08-23T00:17:50.624Z,1692749870.624 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2023-08-23T00:17:50.691Z,1692749870.691 [DataOverHttps](INFO): Sending 360 bytes from file Logs/20230823T001338/Courier0000.lzma
2023-08-23T00:17:51.011Z,1692749871.011 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T00:17:51.693Z,1692749871.693 [DataOverHttps](INFO): Moved sent file to Logs/20230823T001338/Courier0000.lzma.bak
2023-08-23T00:17:51.693Z,1692749871.693 [DataOverHttps](INFO): SBD MOMSN=18581805
2023-08-23T00:17:55.053Z,1692749875.053 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-23T00:16:54.8Z
2023-08-23T00:17:55.053Z,1692749875.053 [Startup:StartupSatComms:B] Stopped
2023-08-23T00:17:55.053Z,1692749875.053 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-23T00:17:55.053Z,1692749875.053 [Startup:StartupSatComms] Stopped
2023-08-23T00:17:55.054Z,1692749875.054 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-23T00:17:55.054Z,1692749875.054 [Startup](INFO): Completed Startup
2023-08-23T00:17:55.054Z,1692749875.054 [MissionManager](INFO): Startup is completed.
2023-08-23T00:17:55.055Z,1692749875.055 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-23T00:17:55.055Z,1692749875.055 [Startup] Stopped
2023-08-23T00:17:55.055Z,1692749875.055 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-23T00:17:55.055Z,1692749875.055 [Startup:A.GoToSurface] Stopped
2023-08-23T00:17:55.055Z,1692749875.055 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-23T00:17:55.453Z,1692749875.453 [MissionManager](IMPORTANT): Started mission Default
2023-08-23T00:17:55.453Z,1692749875.453 [Default] Running Loop=1
2023-08-23T00:17:55.453Z,1692749875.453 [Default](DEBUG): Aggregate::initialize Default
2023-08-23T00:17:55.453Z,1692749875.453 [Default:B.GoToSurface] Running Loop=1
2023-08-23T00:17:55.453Z,1692749875.453 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-23T00:17:55.454Z,1692749875.454 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-23T00:17:55.454Z,1692749875.454 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-23T00:17:55.454Z,1692749875.454 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-23T00:17:55.454Z,1692749875.454 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-23T00:17:55.460Z,1692749875.460 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-23T00:17:55.460Z,1692749875.460 [Default:A.Wait] Running Loop=1
2023-08-23T00:17:55.460Z,1692749875.460 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-23T00:17:59.492Z,1692749879.492 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-23T00:17:59.492Z,1692749879.492 [DAT] Communications Fault, FailCount= 4
2023-08-23T00:17:59.492Z,1692749879.492 [DAT](ERROR): Communications Fault
2023-08-23T00:17:59.495Z,1692749879.495 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-23T00:17:59.895Z,1692749879.895 [DAT](INFO): Powering down
2023-08-23T00:17:59.900Z,1692749879.900 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-23T00:17:59.900Z,1692749879.900 [Rowe_600] No Fault, FailCount= 3
2023-08-23T00:18:01.134Z,1692749881.134 [CBIT](INFO): Clearing failed state for component DAT
2023-08-23T00:18:01.134Z,1692749881.134 [DAT] No Fault, FailCount= 4
2023-08-23T00:18:02.939Z,1692749882.939 [DAT](INFO): Powering up
2023-08-23T00:18:02.939Z,1692749882.939 [DAT](DEBUG): Initializing DAT.
2023-08-23T00:18:06.908Z,1692749886.908 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20230822T234331/Express0005.lzma
2023-08-23T00:18:07.909Z,1692749887.909 [DataOverHttps](INFO): Moved sent file to Logs/20230822T234331/Express0005.lzma.bak
2023-08-23T00:18:07.909Z,1692749887.909 [DataOverHttps](INFO): SBD MOMSN=18581813
2023-08-23T00:18:08.777Z,1692749888.777 [Default:A.Wait](INFO): Done Waiting.
2023-08-23T00:18:08.777Z,1692749888.777 [Default:A.Wait] Stopped
2023-08-23T00:18:08.777Z,1692749888.777 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T00:18:09.182Z,1692749889.182 [Default:CheckIn] Running Loop=1
2023-08-23T00:18:09.182Z,1692749889.182 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T00:18:09.182Z,1692749889.182 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T00:18:09.590Z,1692749889.590 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-23T00:18:12.820Z,1692749892.820 [Rowe_600](FAULT): DVL failed to acquire valid data within timeout.
2023-08-23T00:18:12.820Z,1692749892.820 [Rowe_600] Data Fault, FailCount= 1
2023-08-23T00:18:12.820Z,1692749892.820 [Rowe_600](ERROR): Data Fault
2023-08-23T00:18:12.837Z,1692749892.837 [CBIT](ERROR): Data Fault in component: Rowe_600
2023-08-23T00:18:13.279Z,1692749893.279 [Rowe_600](INFO): Powering down
2023-08-23T00:18:14.039Z,1692749894.039 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-23T00:18:14.039Z,1692749894.039 [Rowe_600] No Fault, FailCount= 1
2023-08-23T00:18:36.642Z,1692749916.642 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-23T00:18:36.642Z,1692749916.642 [Rowe_600] Communications Fault, FailCount= 2
2023-08-23T00:18:36.642Z,1692749916.642 [Rowe_600](ERROR): Communications Fault
2023-08-23T00:18:36.660Z,1692749916.660 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-23T00:18:37.175Z,1692749917.175 [Rowe_600](INFO): Powering down
2023-08-23T00:18:37.901Z,1692749917.901 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-23T00:18:37.901Z,1692749917.901 [Rowe_600] No Fault, FailCount= 2
2023-08-23T00:19:00.481Z,1692749940.481 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-23T00:19:00.481Z,1692749940.481 [Rowe_600] Communications Fault, FailCount= 3
2023-08-23T00:19:00.481Z,1692749940.481 [Rowe_600](ERROR): Communications Fault
2023-08-23T00:19:00.516Z,1692749940.516 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-23T00:19:00.516Z,1692749940.516 [CBIT](CRITICAL): Communications Fault in component: Rowe_600
2023-08-23T00:19:00.955Z,1692749940.955 [Rowe_600](INFO): Powering down
2023-08-23T00:19:00.983Z,1692749940.983 [CBIT](INFO): Critical error at 20230823T001900
2023-08-23T00:19:02.956Z,1692749942.956 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-23T00:19:02.956Z,1692749942.956 [DAT] Communications Fault, FailCount= 5
2023-08-23T00:19:02.956Z,1692749942.956 [DAT](ERROR): Communications Fault
2023-08-23T00:19:03.350Z,1692749943.350 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-23T00:19:03.359Z,1692749943.359 [DAT](INFO): Powering down
2023-08-23T00:19:04.602Z,1692749944.602 [CBIT](INFO): Clearing failed state for component DAT
2023-08-23T00:19:04.602Z,1692749944.602 [DAT] No Fault, FailCount= 5
2023-08-23T00:19:06.411Z,1692749946.411 [DAT](INFO): Powering up
2023-08-23T00:19:06.411Z,1692749946.411 [DAT](DEBUG): Initializing DAT.
2023-08-23T00:19:31.604Z,1692749971.604 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-23T00:19:51.411Z,1692749991.411 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:19:51.411Z,1692749991.411 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-08-23T00:19:51.411Z,1692749991.411 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T00:19:51.413Z,1692749991.413 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:19:51.413Z,1692749991.413 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2023-08-23T00:19:51.413Z,1692749991.413 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T00:19:51.414Z,1692749991.414 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-23T00:19:51.415Z,1692749991.415 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2023-08-23T00:19:51.415Z,1692749991.415 [D