2023-08-22T23:16:04.426Z,1692746164.426 [Supervisor](DEBUG): Initializing supervisor.
2023-08-22T23:16:04.430Z,1692746164.430 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-22T23:16:04.431Z,1692746164.431 [SyncHandler](INFO): Protected caller Thread ID is 823
2023-08-22T23:16:04.431Z,1692746164.431 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-22T23:16:04.432Z,1692746164.432 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-22T23:16:04.433Z,1692746164.433 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 824
2023-08-22T23:16:04.437Z,1692746164.437 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-22T23:16:04.454Z,1692746164.454 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-22T23:16:04.455Z,1692746164.455 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-22T23:16:04.455Z,1692746164.455 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 825
2023-08-22T23:16:04.460Z,1692746164.460 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-22T23:16:04.461Z,1692746164.461 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-22T23:16:04.461Z,1692746164.461 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 826
2023-08-22T23:16:04.463Z,1692746164.463 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-22T23:16:04.464Z,1692746164.464 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-22T23:16:04.464Z,1692746164.464 [logger ThreadHandler](INFO): Protected caller Thread ID is 827
2023-08-22T23:16:04.468Z,1692746164.468 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-22T23:16:04.469Z,1692746164.469 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-22T23:16:04.472Z,1692746164.472 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-22T23:16:04.800Z,1692746164.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-22T23:16:04.801Z,1692746164.801 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-22T23:16:04.880Z,1692746164.880 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-22T23:16:04.986Z,1692746164.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-22T23:16:04.987Z,1692746164.987 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-22T23:16:05.114Z,1692746165.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-22T23:16:05.114Z,1692746165.114 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-22T23:16:05.445Z,1692746165.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-22T23:16:05.445Z,1692746165.445 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-22T23:16:05.540Z,1692746165.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-22T23:16:05.541Z,1692746165.541 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-22T23:16:05.935Z,1692746165.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-22T23:16:05.937Z,1692746165.937 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-22T23:16:06.149Z,1692746166.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-22T23:16:06.149Z,1692746166.149 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-22T23:16:06.228Z,1692746166.228 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-22T23:16:06.445Z,1692746166.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-22T23:16:06.445Z,1692746166.445 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-22T23:16:06.662Z,1692746166.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-22T23:16:06.663Z,1692746166.663 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-22T23:16:07.155Z,1692746167.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-22T23:16:07.155Z,1692746167.155 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-22T23:16:07.337Z,1692746167.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-22T23:16:07.337Z,1692746167.337 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-22T23:16:07.646Z,1692746167.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-22T23:16:07.647Z,1692746167.647 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-22T23:16:08.444Z,1692746168.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-22T23:16:08.445Z,1692746168.445 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-22T23:16:08.947Z,1692746168.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-22T23:16:08.949Z,1692746168.949 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2023-08-22T23:16:08.951Z,1692746168.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2023-08-22T23:16:09.047Z,1692746169.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2023-08-22T23:16:09.163Z,1692746169.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2023-08-22T23:16:09.246Z,1692746169.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2023-08-22T23:16:09.352Z,1692746169.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2023-08-22T23:16:09.449Z,1692746169.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2023-08-22T23:16:09.712Z,1692746169.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-22T23:16:09.713Z,1692746169.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2023-08-22T23:16:09.816Z,1692746169.816 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2023-08-22T23:16:09.909Z,1692746169.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2023-08-22T23:16:10.011Z,1692746170.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2023-08-22T23:16:10.130Z,1692746170.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2023-08-22T23:16:10.214Z,1692746170.214 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-08-22T23:16:10.219Z,1692746170.219 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-22T23:16:10.349Z,1692746170.349 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-22T23:16:10.349Z,1692746170.349 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-22T23:16:10.418Z,1692746170.418 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-22T23:16:10.480Z,1692746170.480 [VerticalControl] Loaded
2023-08-22T23:16:10.480Z,1692746170.480 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-22T23:16:10.483Z,1692746170.483 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-22T23:16:10.525Z,1692746170.525 [HorizontalControl] Loaded
2023-08-22T23:16:10.525Z,1692746170.525 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-22T23:16:10.528Z,1692746170.528 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-22T23:16:10.531Z,1692746170.531 [SpeedControl] Loaded
2023-08-22T23:16:10.531Z,1692746170.531 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-22T23:16:10.534Z,1692746170.534 [LoopControl](DEBUG): Construct LoopControl.
2023-08-22T23:16:10.534Z,1692746170.534 [LoopControl] Loaded
2023-08-22T23:16:10.535Z,1692746170.535 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-22T23:16:10.535Z,1692746170.535 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-22T23:16:10.536Z,1692746170.536 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-22T23:16:10.620Z,1692746170.620 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-22T23:16:10.620Z,1692746170.620 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-22T23:16:11.439Z,1692746171.439 [AHRS_M2] Loaded
2023-08-22T23:16:11.439Z,1692746171.439 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-22T23:16:12.258Z,1692746172.258 [BPC1] Loaded
2023-08-22T23:16:12.258Z,1692746172.258 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-22T23:16:12.399Z,1692746172.399 [DataOverHttps] Loaded
2023-08-22T23:16:12.400Z,1692746172.400 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-22T23:16:12.401Z,1692746172.401 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087F4E0
2023-08-22T23:16:12.401Z,1692746172.401 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 909
2023-08-22T23:16:12.446Z,1692746172.446 [Depth_Keller] Loaded
2023-08-22T23:16:12.446Z,1692746172.446 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-22T23:16:12.451Z,1692746172.451 [DropWeight] Loaded
2023-08-22T23:16:12.451Z,1692746172.451 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-22T23:16:12.515Z,1692746172.515 [NAL9602] Loaded
2023-08-22T23:16:12.515Z,1692746172.515 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-22T23:16:12.546Z,1692746172.546 [Onboard] Loaded
2023-08-22T23:16:12.546Z,1692746172.546 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-22T23:16:12.547Z,1692746172.547 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AF4E0
2023-08-22T23:16:12.548Z,1692746172.548 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 910
2023-08-22T23:16:12.561Z,1692746172.561 [Power24vConverter] Loaded
2023-08-22T23:16:12.561Z,1692746172.561 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-22T23:16:12.575Z,1692746172.575 [Radio_Surface] Loaded
2023-08-22T23:16:12.575Z,1692746172.575 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-22T23:16:12.576Z,1692746172.576 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DF4E0
2023-08-22T23:16:12.577Z,1692746172.577 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 911
2023-08-22T23:16:12.610Z,1692746172.610 [Rowe_600] Loaded
2023-08-22T23:16:12.610Z,1692746172.610 [ComponentRegistry](DEBUG): SyncComponent "Rowe_600" handled in the control thread.
2023-08-22T23:16:12.702Z,1692746172.702 [DAT] Loaded
2023-08-22T23:16:12.703Z,1692746172.703 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-08-22T23:16:12.704Z,1692746172.704 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0
2023-08-22T23:16:12.704Z,1692746172.704 [DAT ThreadHandler](INFO): Protected caller Thread ID is 912
2023-08-22T23:16:12.705Z,1692746172.705 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-22T23:16:12.705Z,1692746172.705 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-22T23:16:12.725Z,1692746172.725 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-22T23:16:12.727Z,1692746172.727 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-22T23:16:12.795Z,1692746172.795 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-22T23:16:12.797Z,1692746172.797 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-22T23:16:12.972Z,1692746172.972 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-22T23:16:12.981Z,1692746172.981 [SBIT] Loaded
2023-08-22T23:16:12.981Z,1692746172.981 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-22T23:16:12.984Z,1692746172.984 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-22T23:16:12.997Z,1692746172.997 [IBIT] Loaded
2023-08-22T23:16:12.997Z,1692746172.997 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-22T23:16:13.003Z,1692746173.003 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-22T23:16:13.110Z,1692746173.110 [CBIT] Loaded
2023-08-22T23:16:13.111Z,1692746173.111 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-22T23:16:13.111Z,1692746173.111 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-22T23:16:13.112Z,1692746173.112 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-22T23:16:13.486Z,1692746173.486 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-22T23:16:13.487Z,1692746173.487 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-22T23:16:13.558Z,1692746173.558 [DepthRateCalculator] Loaded
2023-08-22T23:16:13.558Z,1692746173.558 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-22T23:16:13.563Z,1692746173.563 [PitchRateCalculator] Loaded
2023-08-22T23:16:13.564Z,1692746173.564 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-22T23:16:13.574Z,1692746173.574 [SpeedCalculator] Loaded
2023-08-22T23:16:13.575Z,1692746173.575 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-22T23:16:13.579Z,1692746173.579 [YawRateCalculator] Loaded
2023-08-22T23:16:13.579Z,1692746173.579 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-22T23:16:13.598Z,1692746173.598 [ElevatorOffsetCalculator] Loaded
2023-08-22T23:16:13.598Z,1692746173.598 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-22T23:16:13.599Z,1692746173.599 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-22T23:16:13.600Z,1692746173.600 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-22T23:16:13.790Z,1692746173.790 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-22T23:16:13.790Z,1692746173.790 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-22T23:16:13.929Z,1692746173.929 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-08-22T23:16:13.930Z,1692746173.930 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-08-22T23:16:13.974Z,1692746173.974 [DeadReckonUsingSpeedCalculator] Loaded
2023-08-22T23:16:13.974Z,1692746173.974 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-08-22T23:16:14.017Z,1692746174.017 [DeadReckonWithRespectToSeafloor] Loaded
2023-08-22T23:16:14.018Z,1692746174.018 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2023-08-22T23:16:14.032Z,1692746174.032 [NavChart] Loaded
2023-08-22T23:16:14.032Z,1692746174.032 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-22T23:16:14.038Z,1692746174.038 [UniversalFixResidualReporter] Loaded
2023-08-22T23:16:14.038Z,1692746174.038 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-22T23:16:14.038Z,1692746174.038 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-22T23:16:14.039Z,1692746174.039 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-22T23:16:14.202Z,1692746174.202 [BuoyancyServo] Loaded
2023-08-22T23:16:14.202Z,1692746174.202 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-22T23:16:14.223Z,1692746174.223 [ElevatorServo] Loaded
2023-08-22T23:16:14.223Z,1692746174.223 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-22T23:16:14.244Z,1692746174.244 [MassServo] Loaded
2023-08-22T23:16:14.244Z,1692746174.244 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-22T23:16:14.263Z,1692746174.263 [RudderServo] Loaded
2023-08-22T23:16:14.263Z,1692746174.263 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-22T23:16:14.278Z,1692746174.278 [ThrusterHE] Loaded
2023-08-22T23:16:14.279Z,1692746174.279 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-22T23:16:14.279Z,1692746174.279 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-22T23:16:14.280Z,1692746174.280 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-22T23:16:14.508Z,1692746174.508 [CTD_Seabird] Loaded
2023-08-22T23:16:14.508Z,1692746174.508 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-22T23:16:14.509Z,1692746174.509 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B694E0
2023-08-22T23:16:14.509Z,1692746174.509 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 913
2023-08-22T23:16:14.543Z,1692746174.543 [ESPComponent] Loaded
2023-08-22T23:16:14.544Z,1692746174.544 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-08-22T23:16:14.565Z,1692746174.565 [PAR_Licor] Loaded
2023-08-22T23:16:14.565Z,1692746174.565 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-22T23:16:14.607Z,1692746174.607 [WetLabsBB2FL] Loaded
2023-08-22T23:16:14.607Z,1692746174.607 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-22T23:16:14.608Z,1692746174.608 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B994E0
2023-08-22T23:16:14.608Z,1692746174.608 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 914
2023-08-22T23:16:14.609Z,1692746174.609 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-22T23:16:14.615Z,1692746174.615 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-22T23:16:14.618Z,1692746174.618 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-22T23:16:14.629Z,1692746174.629 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-22T23:16:14.630Z,1692746174.630 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC94E0
2023-08-22T23:16:14.631Z,1692746174.631 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 915
2023-08-22T23:16:14.635Z,1692746174.635 [Supervisor](INFO): Main Thread ID is 817
2023-08-22T23:16:14.635Z,1692746174.635 [Supervisor](DEBUG): Running supervisor.
2023-08-22T23:16:14.636Z,1692746174.636 [CommandExec ThreadHandler](INFO): Handler Thread ID is 916
2023-08-22T23:16:14.636Z,1692746174.636 [CommandExec](INFO): Initializing the command executive.
2023-08-22T23:16:14.638Z,1692746174.638 [CommandLine ThreadHandler](INFO): Handler Thread ID is 917
2023-08-22T23:16:14.640Z,1692746174.640 [controlThread ThreadHandler](INFO): Handler Thread ID is 918
2023-08-22T23:16:14.641Z,1692746174.641 [controlThread](DEBUG): Initializing ControlThread
2023-08-22T23:16:14.641Z,1692746174.641 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-22T23:16:14.643Z,1692746174.643 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-22T23:16:14.644Z,1692746174.644 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-22T23:16:14.644Z,1692746174.644 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-22T23:16:14.649Z,1692746174.649 [SBIT](INFO): Initialize SBIT Component.
2023-08-22T23:16:14.649Z,1692746174.649 [SBIT](IMPORTANT): git: 2023-08-02
2023-08-22T23:16:14.649Z,1692746174.649 [SBIT](INFO): git hash: 04bff54855c04b79675eb3036915d66bd94ee3d9
2023-08-22T23:16:14.650Z,1692746174.650 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-22T23:16:14.651Z,1692746174.651 [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-22T23:16:14.652Z,1692746174.652 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2023-08-22T23:16:14.653Z,1692746174.653 [IBIT](INFO): Initialize IBIT Component.
2023-08-22T23:16:14.653Z,1692746174.653 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-22T23:16:14.654Z,1692746174.654 [logger ThreadHandler](INFO): Handler Thread ID is 919
2023-08-22T23:16:14.667Z,1692746174.667 [CBIT](DEBUG): Initialized mux pins.
2023-08-22T23:16:14.667Z,1692746174.667 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2023-08-22T23:16:14.667Z,1692746174.667 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-22T23:16:14.675Z,1692746174.675 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 920
2023-08-22T23:16:14.676Z,1692746174.676 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-22T23:16:14.687Z,1692746174.687 [Onboard ThreadHandler](INFO): Handler Thread ID is 921
2023-08-22T23:16:14.691Z,1692746174.691 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-22T23:16:14.692Z,1692746174.692 [CBIT](DEBUG): Initializing heartbeat.
2023-08-22T23:16:14.708Z,1692746174.708 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 922
2023-08-22T23:16:14.723Z,1692746174.723 [DAT ThreadHandler](INFO): Handler Thread ID is 923
2023-08-22T23:16:14.724Z,1692746174.724 [DAT](INFO): Powering up
2023-08-22T23:16:14.724Z,1692746174.724 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:16:14.728Z,1692746174.728 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 925
2023-08-22T23:16:14.729Z,1692746174.729 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-22T23:16:14.732Z,1692746174.732 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 927
2023-08-22T23:16:14.736Z,1692746174.736 [WetLabsBB2FL](INFO): Powering up
2023-08-22T23:16:14.737Z,1692746174.737 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 929
2023-08-22T23:16:14.744Z,1692746174.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2023-08-22T23:16:14.744Z,1692746174.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2023-08-22T23:16:14.745Z,1692746174.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2023-08-22T23:16:14.745Z,1692746174.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2023-08-22T23:16:14.745Z,1692746174.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-08-22T23:16:14.745Z,1692746174.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-08-22T23:16:14.745Z,1692746174.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-08-22T23:16:14.746Z,1692746174.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-08-22T23:16:14.746Z,1692746174.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2023-08-22T23:16:14.746Z,1692746174.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2023-08-22T23:16:14.746Z,1692746174.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2023-08-22T23:16:14.746Z,1692746174.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2023-08-22T23:16:14.763Z,1692746174.763 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-22T23:16:14.763Z,1692746174.763 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-22T23:16:14.803Z,1692746174.803 [CBIT](DEBUG): Backplane powered.
2023-08-22T23:16:14.803Z,1692746174.803 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-22T23:16:14.803Z,1692746174.803 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-22T23:16:14.804Z,1692746174.804 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-22T23:16:14.804Z,1692746174.804 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-22T23:16:14.804Z,1692746174.804 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-22T23:16:14.805Z,1692746174.805 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-22T23:16:14.806Z,1692746174.806 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-22T23:16:14.807Z,1692746174.807 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-22T23:16:14.812Z,1692746174.812 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-22T23:16:14.847Z,1692746174.847 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-22T23:16:14.875Z,1692746174.875 [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-22T23:16:14.876Z,1692746174.876 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-22T23:16:14.933Z,1692746174.933 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-22T23:16:14.934Z,1692746174.934 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-22T23:16:14.937Z,1692746174.937 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-22T23:16:14.977Z,1692746174.977 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-22T23:16:14.979Z,1692746174.979 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-22T23:16:14.985Z,1692746174.985 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-22T23:16:15.001Z,1692746175.001 [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-22T23:16:15.006Z,1692746175.006 [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-22T23:16:15.023Z,1692746175.023 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-22T23:16:15.115Z,1692746175.115 [Radio_Surface](INFO): Powering up
2023-08-22T23:16:15.182Z,1692746175.182 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2023-08-22T23:16:15.183Z,1692746175.183 [Power24vConverter](INFO): Powering up.
2023-08-22T23:16:15.197Z,1692746175.197 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-08-22T23:16:15.230Z,1692746175.230 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-22T23:16:15.235Z,1692746175.235 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-22T23:16:15.236Z,1692746175.236 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-22T23:16:15.243Z,1692746175.243 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-22T23:16:15.244Z,1692746175.244 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-22T23:16:15.251Z,1692746175.251 [MassServo](DEBUG): Initializing MassServo.
2023-08-22T23:16:15.252Z,1692746175.252 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-22T23:16:15.260Z,1692746175.260 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-22T23:16:15.260Z,1692746175.260 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-22T23:16:15.267Z,1692746175.267 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-22T23:16:15.469Z,1692746175.469 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-08-22T23:16:15.469Z,1692746175.469 [DropWeight] Hardware Fault, FailCount= 1
2023-08-22T23:16:15.469Z,1692746175.469 [DropWeight](ERROR): Hardware Fault
2023-08-22T23:16:15.490Z,1692746175.490 [CommandExec](FAULT): Scheduling is paused
2023-08-22T23:16:15.495Z,1692746175.495 [CBIT](INFO): Critical error at 20230822T231615
2023-08-22T23:16:15.495Z,1692746175.495 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-08-22T23:16:15.498Z,1692746175.498 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-08-22T23:16:15.498Z,1692746175.498 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-08-22T23:16:15.854Z,1692746175.854 [CBIT](INFO): Critical error at 20230822T231615
2023-08-22T23:16:16.843Z,1692746176.843 [WetLabsBB2FL](INFO): Powering down
2023-08-22T23:16:18.241Z,1692746178.241 [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-22T23:16:18.644Z,1692746178.644 [AHRS_M2](DEBUG): chan0Format 2 set drop

2023-08-22T23:16:19.048Z,1692746179.048 [AHRS_M2](DEBUG): chan0Trigger 5 set drop

2023-08-22T23:16:19.452Z,1692746179.452 [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-22T23:16:19.452Z,1692746179.452 [AHRS_M2](DEBUG): chan0EnableBit pitch dvid@ set drop

2023-08-22T23:16:19.856Z,1692746179.856 [AHRS_M2](DEBUG): chan0EnableBit roll dvid@ set drop

2023-08-22T23:16:20.260Z,1692746180.260 [AHRS_M2](DEBUG): chan0EnableBit yaw dvid@ set drop

2023-08-22T23:16:20.664Z,1692746180.664 [AHRS_M2](DEBUG): chan0EnableBit magp dvid@ set drop

2023-08-22T23:16:20.844Z,1692746180.844 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-22T23:16:21.068Z,1692746181.068 [AHRS_M2](DEBUG): chan0EnableBit accelp dvid@ set drop

2023-08-22T23:16:21.472Z,1692746181.472 [AHRS_M2](DEBUG): chan0EnableBit gyrop dvid@ set drop

2023-08-22T23:16:21.876Z,1692746181.876 [AHRS_M2](DEBUG): chan0EnableBit yawErrEst dvid@ set drop

2023-08-22T23:16:22.280Z,1692746182.280 [AHRS_M2](DEBUG): chan0EnableBit temperature dvid@ set drop

2023-08-22T23:16:22.684Z,1692746182.684 [AHRS_M2](DEBUG): chan0EnableBit magBufferActiveIndex dvid@ set drop

2023-08-22T23:16:23.088Z,1692746183.088 [AHRS_M2](DEBUG): chan0TriggerDivisor 40 set drop

2023-08-22T23:16:24.775Z,1692746184.775 [CBIT](CRITICAL): Environmental Failure. Press:14.774522 PSI. Humidity:56%. Temp:25 C. ABORTING MISSION
2023-08-22T23:16:25.149Z,1692746185.149 [CBIT](INFO): Critical error at 20230822T231624
2023-08-22T23:16:33.188Z,1692746193.188 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-08-22T23:16:33.188Z,1692746193.188 [AHRS_M2] Data Fault, FailCount= 1
2023-08-22T23:16:33.188Z,1692746193.188 [AHRS_M2](ERROR): Data Fault
2023-08-22T23:16:33.251Z,1692746193.251 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-08-22T23:16:33.252Z,1692746193.252 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2023-08-22T23:16:33.641Z,1692746193.641 [CBIT](INFO): Critical error at 20230822T231633
2023-08-22T23:16:37.640Z,1692746197.640 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-22T23:16:37.640Z,1692746197.640 [Rowe_600] Communications Fault, FailCount= 1
2023-08-22T23:16:37.640Z,1692746197.640 [Rowe_600](ERROR): Communications Fault
2023-08-22T23:16:37.699Z,1692746197.699 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-22T23:16:38.115Z,1692746198.115 [Rowe_600](INFO): Powering down
2023-08-22T23:16:38.895Z,1692746198.895 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:16:38.895Z,1692746198.895 [Rowe_600] No Fault, FailCount= 1
2023-08-22T23:16:42.892Z,1692746202.892 [NAL9602](INFO): Powering up NAL9602
2023-08-22T23:16:53.800Z,1692746213.800 [NAL9602](INFO): NAL9602 initialized
2023-08-22T23:17:01.477Z,1692746221.477 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-22T23:17:01.477Z,1692746221.477 [Rowe_600] Communications Fault, FailCount= 2
2023-08-22T23:17:01.477Z,1692746221.477 [Rowe_600](ERROR): Communications Fault
2023-08-22T23:17:01.493Z,1692746221.493 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-22T23:17:01.967Z,1692746221.967 [Rowe_600](INFO): Powering down
2023-08-22T23:17:02.724Z,1692746222.724 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:17:02.724Z,1692746222.724 [Rowe_600] No Fault, FailCount= 2
2023-08-22T23:17:14.975Z,1692746234.975 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:17:14.975Z,1692746234.976 [DAT] Communications Fault, FailCount= 1
2023-08-22T23:17:14.976Z,1692746234.976 [DAT](ERROR): Communications Fault
2023-08-22T23:17:15.233Z,1692746235.233 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:17:15.379Z,1692746235.379 [DAT](INFO): Powering down
2023-08-22T23:17:16.440Z,1692746236.440 [CBIT](INFO): Clearing failed state for component DAT
2023-08-22T23:17:16.440Z,1692746236.440 [DAT] No Fault, FailCount= 1
2023-08-22T23:17:18.427Z,1692746238.427 [DAT](INFO): Powering up
2023-08-22T23:17:18.427Z,1692746238.427 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:17:25.314Z,1692746245.314 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-22T23:17:25.315Z,1692746245.315 [Rowe_600] Communications Fault, FailCount= 3
2023-08-22T23:17:25.315Z,1692746245.315 [Rowe_600](ERROR): Communications Fault
2023-08-22T23:17:25.331Z,1692746245.331 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-22T23:17:25.332Z,1692746245.332 [CBIT](CRITICAL): Communications Fault in component: Rowe_600
2023-08-22T23:17:25.783Z,1692746245.783 [Rowe_600](INFO): Powering down
2023-08-22T23:17:25.803Z,1692746245.803 [CBIT](INFO): Critical error at 20230822T231725
2023-08-22T23:17:26.134Z,1692746246.134 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-22T23:17:26.139Z,1692746246.139 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-22T23:17:37.100Z,1692746257.100 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.000380
CHAN A1 (24V): 0.052010
CHAN A2 (12V): 0.000316
CHAN A3 (5V): -0.001362
CHAN B0 (3.3V): 0.000460
CHAN B1 (3.15aV): 0.000090
CHAN B2 (3.15bV): 0.000199
CHAN B3 (GND): 0.000112
OPEN: 0.006527
Full Scale: +/- 1 mA
2023-08-22T23:18:15.063Z,1692746295.063 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:18:15.063Z,1692746295.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-08-22T23:18:15.063Z,1692746295.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-22T23:18:15.065Z,1692746295.065 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:18:15.065Z,1692746295.065 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-08-22T23:18:15.065Z,1692746295.065 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-22T23:18:15.066Z,1692746295.066 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:18:15.066Z,1692746295.066 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2023-08-22T23:18:15.066Z,1692746295.066 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-08-22T23:18:15.077Z,1692746295.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-22T23:18:15.077Z,1692746295.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-22T23:18:15.078Z,1692746295.078 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-08-22T23:18:15.473Z,1692746295.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-22T23:18:15.473Z,1692746295.473 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-08-22T23:18:15.474Z,1692746295.474 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-22T23:18:15.474Z,1692746295.474 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-08-22T23:18:15.474Z,1692746295.474 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-08-22T23:18:15.474Z,1692746295.474 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2023-08-22T23:18:15.851Z,1692746295.851 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-22T23:18:18.435Z,1692746298.435 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:18:18.435Z,1692746298.435 [DAT] Communications Fault, FailCount= 2
2023-08-22T23:18:18.436Z,1692746298.436 [DAT](ERROR): Communications Fault
2023-08-22T23:18:18.659Z,1692746298.659 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:18:18.839Z,1692746298.839 [DAT](INFO): Powering down
2023-08-22T23:18:19.869Z,1692746299.869 [CBIT](INFO): Clearing failed state for component DAT
2023-08-22T23:18:19.869Z,1692746299.869 [DAT] No Fault, FailCount= 2
2023-08-22T23:18:20.271Z,1692746300.271 [SBIT](IMPORTANT): SBIT PASSED
2023-08-22T23:18:20.271Z,1692746300.271 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-08-22T23:18:20.681Z,1692746300.681 [MissionManager](IMPORTANT): Started mission Startup
2023-08-22T23:18:20.681Z,1692746300.681 [Startup] Running Loop=1
2023-08-22T23:18:20.681Z,1692746300.681 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-22T23:18:20.681Z,1692746300.681 [Startup:A.GoToSurface] Running Loop=1
2023-08-22T23:18:20.681Z,1692746300.681 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-22T23:18:20.682Z,1692746300.682 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-22T23:18:20.682Z,1692746300.682 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-22T23:18:20.683Z,1692746300.683 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-22T23:18:20.683Z,1692746300.683 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-22T23:18:20.683Z,1692746300.683 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-22T23:18:20.685Z,1692746300.685 [Startup:StartupSatComms] Running Loop=1
2023-08-22T23:18:20.685Z,1692746300.685 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-22T23:18:20.685Z,1692746300.685 [Startup:StartupSatComms:A] Running Loop=1
2023-08-22T23:18:21.086Z,1692746301.086 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-22T23:18:21.887Z,1692746301.887 [DAT](INFO): Powering up
2023-08-22T23:18:21.887Z,1692746301.887 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:18:48.370Z,1692746328.370 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005276
2023-08-22T23:19:20.865Z,1692746360.865 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-22T23:18:20.7Z
2023-08-22T23:19:20.865Z,1692746360.865 [Startup:StartupSatComms:A] Stopped
2023-08-22T23:19:20.865Z,1692746360.865 [Startup:StartupSatComms:B] Running Loop=1
2023-08-22T23:19:21.270Z,1692746361.270 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-22T23:19:21.899Z,1692746361.899 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:19:21.899Z,1692746361.899 [DAT] Communications Fault, FailCount= 3
2023-08-22T23:19:21.899Z,1692746361.899 [DAT](ERROR): Communications Fault
2023-08-22T23:19:22.105Z,1692746362.105 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:19:22.303Z,1692746362.303 [DAT](INFO): Powering down
2023-08-22T23:19:23.306Z,1692746363.306 [CBIT](INFO): Clearing failed state for component DAT
2023-08-22T23:19:23.306Z,1692746363.306 [DAT] No Fault, FailCount= 3
2023-08-22T23:19:25.351Z,1692746365.351 [DAT](INFO): Powering up
2023-08-22T23:19:25.351Z,1692746365.351 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:19:27.742Z,1692746367.742 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230822T230145/Courier0007.lzma
2023-08-22T23:19:28.745Z,1692746368.745 [DataOverHttps](INFO): Moved sent file to Logs/20230822T230145/Courier0007.lzma.bak
2023-08-22T23:19:28.745Z,1692746368.745 [DataOverHttps](INFO): SBD MOMSN=18581285
2023-08-22T23:19:43.000Z,1692746384.000 [DataOverHttps](INFO): Sending 359 bytes from file Logs/20230822T231604/Courier0000.lzma
2023-08-22T23:19:45.000Z,1692746385.000 [DataOverHttps](INFO): Moved sent file to Logs/20230822T231604/Courier0000.lzma.bak
2023-08-22T23:19:45.001Z,1692746385.001 [DataOverHttps](INFO): SBD MOMSN=18581288
2023-08-22T23:20:00.154Z,1692746400.154 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230822T230145/Express0008.lzma
2023-08-22T23:20:01.156Z,1692746401.156 [DataOverHttps](INFO): Moved sent file to Logs/20230822T230145/Express0008.lzma.bak
2023-08-22T23:20:01.157Z,1692746401.157 [DataOverHttps](INFO): SBD MOMSN=18581297
2023-08-22T23:20:16.232Z,1692746416.232 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:20:16.232Z,1692746416.232 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-08-22T23:20:16.232Z,1692746416.232 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-22T23:20:16.233Z,1692746416.233 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:20:16.233Z,1692746416.233 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2023-08-22T23:20:16.233Z,1692746416.233 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-22T23:20:16.259Z,1692746416.259 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:20:16.259Z,1692746416.259 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2023-08-22T23:20:16.259Z,1692746416.259 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-08-22T23:20:16.280Z,1692746416.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-22T23:20:16.280Z,1692746416.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-22T23:20:16.280Z,1692746416.280 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-08-22T23:20:16.368Z,1692746416.368 [DataOverHttps](INFO): Sending 900 bytes from file Logs/20230822T231604/Express0001.lzma
2023-08-22T23:20:16.641Z,1692746416.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-22T23:20:16.641Z,1692746416.641 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-08-22T23:20:16.642Z,1692746416.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-22T23:20:16.642Z,1692746416.642 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2023-08-22T23:20:16.643Z,1692746416.643 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-08-22T23:20:16.643Z,1692746416.643 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2023-08-22T23:20:17.039Z,1692746417.039 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-22T23:20:17.368Z,1692746417.368 [DataOverHttps](INFO): Moved sent file to Logs/20230822T231604/Express0001.lzma.bak
2023-08-22T23:20:17.369Z,1692746417.369 [DataOverHttps](INFO): SBD MOMSN=18581299
2023-08-22T23:20:18.685Z,1692746418.685 [Startup:StartupSatComms:B] Stopped
2023-08-22T23:20:18.685Z,1692746418.685 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-22T23:20:18.685Z,1692746418.685 [Startup:StartupSatComms] Stopped
2023-08-22T23:20:18.686Z,1692746418.686 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-22T23:20:18.686Z,1692746418.686 [Startup](INFO): Completed Startup
2023-08-22T23:20:18.687Z,1692746418.687 [MissionManager](INFO): Startup is completed.
2023-08-22T23:20:18.687Z,1692746418.687 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-22T23:20:18.687Z,1692746418.687 [Startup] Stopped
2023-08-22T23:20:18.687Z,1692746418.687 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-22T23:20:18.687Z,1692746418.687 [Startup:A.GoToSurface] Stopped
2023-08-22T23:20:18.687Z,1692746418.687 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-22T23:20:19.049Z,1692746419.049 [MissionManager](IMPORTANT): Started mission Default
2023-08-22T23:20:19.049Z,1692746419.049 [Default] Running Loop=1
2023-08-22T23:20:19.049Z,1692746419.049 [Default](DEBUG): Aggregate::initialize Default
2023-08-22T23:20:19.049Z,1692746419.049 [Default:B.GoToSurface] Running Loop=1
2023-08-22T23:20:19.049Z,1692746419.049 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-22T23:20:19.049Z,1692746419.049 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-22T23:20:19.050Z,1692746419.050 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-22T23:20:19.050Z,1692746419.050 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-22T23:20:19.050Z,1692746419.050 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-22T23:20:19.051Z,1692746419.051 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-22T23:20:19.051Z,1692746419.051 [Default:A.Wait] Running Loop=1
2023-08-22T23:20:19.051Z,1692746419.051 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-22T23:20:25.532Z,1692746425.532 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:20:25.532Z,1692746425.532 [Rowe_600] No Fault, FailCount= 3
2023-08-22T23:20:25.591Z,1692746425.591 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:20:25.591Z,1692746425.591 [DAT] Communications Fault, FailCount= 4
2023-08-22T23:20:25.591Z,1692746425.591 [DAT](ERROR): Communications Fault
2023-08-22T23:20:25.919Z,1692746425.919 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:20:25.995Z,1692746425.995 [DAT](INFO): Powering down
2023-08-22T23:20:27.158Z,1692746427.158 [CBIT](INFO): Clearing failed state for component DAT
2023-08-22T23:20:27.158Z,1692746427.158 [DAT] No Fault, FailCount= 4
2023-08-22T23:20:29.043Z,1692746429.043 [DAT](INFO): Powering up
2023-08-22T23:20:29.043Z,1692746429.043 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:20:32.400Z,1692746432.400 [Default:A.Wait](INFO): Done Waiting.
2023-08-22T23:20:32.400Z,1692746432.400 [Default:A.Wait] Stopped
2023-08-22T23:20:32.400Z,1692746432.400 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-22T23:20:32.797Z,1692746432.797 [Default:CheckIn] Running Loop=1
2023-08-22T23:20:32.798Z,1692746432.798 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-22T23:20:32.798Z,1692746432.798 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-22T23:20:33.206Z,1692746433.206 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-22T23:20:38.442Z,1692746438.442 [Rowe_600](FAULT): DVL failed to acquire valid data within timeout.
2023-08-22T23:20:38.442Z,1692746438.442 [Rowe_600] Data Fault, FailCount= 1
2023-08-22T23:20:38.442Z,1692746438.442 [Rowe_600](ERROR): Data Fault
2023-08-22T23:20:38.460Z,1692746438.460 [CBIT](ERROR): Data Fault in component: Rowe_600
2023-08-22T23:20:38.903Z,1692746438.903 [Rowe_600](INFO): Powering down
2023-08-22T23:20:39.657Z,1692746439.657 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:20:39.657Z,1692746439.657 [Rowe_600] No Fault, FailCount= 1
2023-08-22T23:21:02.265Z,1692746462.265 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-22T23:21:02.266Z,1692746462.266 [Rowe_600] Communications Fault, FailCount= 2
2023-08-22T23:21:02.266Z,1692746462.266 [Rowe_600](ERROR): Communications Fault
2023-08-22T23:21:02.303Z,1692746462.303 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-22T23:21:02.735Z,1692746462.735 [Rowe_600](INFO): Powering down
2023-08-22T23:21:03.508Z,1692746463.508 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:21:03.508Z,1692746463.508 [Rowe_600] No Fault, FailCount= 2
2023-08-22T23:21:26.102Z,1692746486.102 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-22T23:21:26.102Z,1692746486.102 [Rowe_600] Communications Fault, FailCount= 3
2023-08-22T23:21:26.102Z,1692746486.102 [Rowe_600](ERROR): Communications Fault
2023-08-22T23:21:26.137Z,1692746486.137 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-22T23:21:26.138Z,1692746486.138 [CBIT](CRITICAL): Communications Fault in component: Rowe_600
2023-08-22T23:21:26.575Z,1692746486.575 [Rowe_600](INFO): Powering down
2023-08-22T23:21:26.603Z,1692746486.603 [CBIT](INFO): Critical error at 20230822T232126
2023-08-22T23:21:29.051Z,1692746489.051 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:21:29.051Z,1692746489.051 [DAT] Communications Fault, FailCount= 5
2023-08-22T23:21:29.051Z,1692746489.051 [DAT](ERROR): Communications Fault
2023-08-22T23:21:29.358Z,1692746489.358 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:21:29.456Z,1692746489.456 [DAT](INFO): Powering down
2023-08-22T23:21:30.582Z,1692746490.582 [CBIT](INFO): Clearing failed state for component DAT
2023-08-22T23:21:30.582Z,1692746490.582 [DAT] No Fault, FailCount= 5
2023-08-22T23:21:31.751Z,1692746491.751 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-08-22T23:21:32.503Z,1692746492.503 [DAT](INFO): Powering up
2023-08-22T23:21:32.503Z,1692746492.503 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:21:57.212Z,1692746517.212 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-22T23:22:17.414Z,1692746537.414 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:22:17.414Z,1692746537.414 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-08-22T23:22:17.414Z,1692746537.414 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-22T23:22:17.416Z,1692746537.416 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:22:17.416Z,1692746537.416 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2023-08-22T23:22:17.416Z,1692746537.416 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-22T23:22:17.417Z,1692746537.417 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:22:17.417Z,1692746537.417 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2023-08-22T23:22:17.417Z,1692746537.417 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-08-22T23:22:17.429Z,1692746537.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-22T23:22:17.429Z,1692746537.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-22T23:22:17.429Z,1692746537.429 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-08-22T23:22:17.837Z,1692746537.837 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-22T23:22:17.837Z,1692746537.837 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-08-22T23:22:17.837Z,1692746537.837 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-22T23:22:17.837Z,1692746537.837 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2023-08-22T23:22:17.838Z,1692746537.838 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-08-22T23:22:17.838Z,1692746537.838 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2023-08-22T23:22:18.216Z,1692746538.216 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-22T23:22:32.519Z,1692746552.519 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:22:32.519Z,1692746552.519 [DAT] Communications Fault, FailCount= 6
2023-08-22T23:22:32.519Z,1692746552.519 [DAT](ERROR): Communications Fault
2023-08-22T23:22:32.779Z,1692746552.779 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:22:32.923Z,1692746552.923 [DAT](INFO): Powering down
2023-08-22T23:22:33.999Z,1692746553.999 [CBIT](INFO): Clearing failed state for component DAT
2023-08-22T23:22:33.000Z,1692746554.000 [DAT] No Fault, FailCount= 6
2023-08-22T23:22:35.971Z,1692746555.971 [DAT](INFO): Powering up
2023-08-22T23:22:35.971Z,1692746555.971 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:23:36.031Z,1692746616.031 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:23:36.031Z,1692746616.031 [DAT] Communications Fault, FailCount= 7
2023-08-22T23:23:36.031Z,1692746616.031 [DAT](ERROR): Communications Fault
2023-08-22T23:23:36.215Z,1692746616.215 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:23:36.435Z,1692746616.435 [DAT](INFO): Powering down
2023-08-22T23:23:37.429Z,1692746617.429 [CBIT](INFO): Clearing failed state for component DAT
2023-08-22T23:23:37.429Z,1692746617.429 [DAT] No Fault, FailCount= 7
2023-08-22T23:23:39.483Z,1692746619.483 [DAT](INFO): Powering up
2023-08-22T23:23:39.483Z,1692746619.483 [DAT](DEBUG): Initializing DAT.
2023-08-22T23:24:18.222Z,1692746658.222 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:24:18.222Z,1692746658.222 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-08-22T23:24:18.222Z,1692746658.222 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-22T23:24:18.224Z,1692746658.224 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:24:18.224Z,1692746658.224 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2023-08-22T23:24:18.224Z,1692746658.224 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-22T23:24:18.226Z,1692746658.226 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-08-22T23:24:18.226Z,1692746658.226 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2023-08-22T23:24:18.226Z,1692746658.226 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-08-22T23:24:18.237Z,1692746658.237 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-22T23:24:18.237Z,1692746658.237 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-22T23:24:18.237Z,1692746658.237 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-08-22T23:24:18.632Z,1692746658.632 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-22T23:24:18.632Z,1692746658.632 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-08-22T23:24:18.633Z,1692746658.633 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-22T23:24:18.633Z,1692746658.633 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2023-08-22T23:24:18.633Z,1692746658.633 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-08-22T23:24:18.633Z,1692746658.633 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2023-08-22T23:24:19.019Z,1692746659.019 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-22T23:24:26.324Z,1692746666.324 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:24:26.324Z,1692746666.324 [Rowe_600] No Fault, FailCount= 3
2023-08-22T23:24:27.084Z,1692746667.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-22T23:24:39.213Z,1692746679.213 [Rowe_600](FAULT): DVL failed to acquire valid data within timeout.
2023-08-22T23:24:39.213Z,1692746679.213 [Rowe_600] Data Fault, FailCount= 1
2023-08-22T23:24:39.213Z,1692746679.213 [Rowe_600](ERROR): Data Fault
2023-08-22T23:24:39.246Z,1692746679.246 [CBIT](ERROR): Data Fault in component: Rowe_600
2023-08-22T23:24:39.687Z,1692746679.687 [Rowe_600](INFO): Powering down
2023-08-22T23:24:39.723Z,1692746679.723 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-08-22T23:24:39.723Z,1692746679.723 [DAT] Communications Fault, FailCount= 8
2023-08-22T23:24:39.723Z,1692746679.723 [DAT](ERROR): Communications Fault
2023-08-22T23:24:40.045Z,1692746680.045 [CBIT](ERROR): Communications Fault in component: DAT
2023-08-22T23:24:40.046Z,1692746680.046 [CBIT](FAULT): Communications Fault in component: DAT
2023-08-22T23:24:40.127Z,1692746680.127 [DAT](INFO): Powering down
2023-08-22T23:24:40.455Z,1692746680.455 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:24:40.456Z,1692746680.456 [Rowe_600] No Fault, FailCount= 1
2023-08-22T23:24:42.841Z,1692746682.841 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-22T23:24:57.846Z,1692746697.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-22T23:25:03.049Z,1692746703.049 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-22T23:25:03.049Z,1692746703.049 [Rowe_600] Communications Fault, FailCount= 2
2023-08-22T23:25:03.050Z,1692746703.050 [Rowe_600](ERROR): Communications Fault
2023-08-22T23:25:03.086Z,1692746703.086 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-22T23:25:03.523Z,1692746703.523 [Rowe_600](INFO): Powering down
2023-08-22T23:25:04.279Z,1692746704.279 [CBIT](INFO): Clearing failed state for component Rowe_600
2023-08-22T23:25:04.279Z,1692746704.279 [Rowe_600] No Fault, FailCount= 2
2023-08-22T23:25:13.140Z,1692746713.140 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-22T23:25:26.885Z,1692746726.885 [Rowe_600](ERROR): No DVL communication! Re-initializing
2023-08-22T23:25:26.885Z,1692746726.885 [Rowe_600] Communications Fault, FailCount= 3
2023-08-22T23:25:26.885Z,1692746726.885 [Rowe_600](ERROR): Communications Fault
2023-08-22T23:25:26.902Z,1692746726.902 [CBIT](ERROR): Communications Fault in component: Rowe_600
2023-08-22T23:25:26.903Z,1692746726.903 [CBIT](CRITICAL): Communications Fault in component: Rowe_600
2023-08-22T23:25:27.355Z,1692746727.355 [Rowe_600](INFO): Powering down
2023-08-22T23:25:27.391Z,1692746727.391 [CBIT](INFO): Critical error at 20230822T232526
2023-08-22T23:25:28.492Z,1692746728.492 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-08-22T23:25:30.876Z,1692746730.876 [CommandExec](IMPORTANT): got command restart system
2023-08-22T23:25:32.931Z,1692746732.931 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:32.931Z,1692746732.931 [CommandExec](INFO): Uninitializing the command executive.
2023-08-22T23:25:32.931Z,1692746732.931 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-22T23:25:32.932Z,1692746732.932 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:32.982Z,1692746732.982 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-08-22T23:20:32.8Z
2023-08-22T23:25:32.982Z,1692746732.982 [Default:CheckIn:Read_GPS] Stopped
2023-08-22T23:25:32.983Z,1692746732.983 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-22T23:25:33.123Z,1692746733.123 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-22T23:25:33.123Z,1692746733.123 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-22T23:25:33.123Z,1692746733.123 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:33.124Z,1692746733.124 [NavChartDb](INFO): Join timeout helper Thread ID is 981
2023-08-22T23:25:33.179Z,1692746733.179 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:33.179Z,1692746733.179 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:33.183Z,1692746733.183 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-22T23:25:33.183Z,1692746733.183 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:33.183Z,1692746733.183 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 982
2023-08-22T23:25:33.354Z,1692746733.354 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-22T23:25:33.371Z,1692746733.371 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:33.371Z,1692746733.371 [WetLabsBB2FL](INFO): Powering down
2023-08-22T23:25:33.372Z,1692746733.372 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:33.372Z,1692746733.372 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-22T23:25:33.373Z,1692746733.373 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:33.373Z,1692746733.373 [CTD_Seabird](INFO): Join timeout helper Thread ID is 983
2023-08-22T23:25:34.043Z,1692746734.043 [CTD_Seabird](INFO): Powering down
2023-08-22T23:25:34.055Z,1692746734.055 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:34.055Z,1692746734.055 [CTD_Seabird](INFO): Powering down
2023-08-22T23:25:34.067Z,1692746734.067 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:34.075Z,1692746734.075 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-08-22T23:25:34.075Z,1692746734.075 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:34.076Z,1692746734.076 [DAT](INFO): Join timeout helper Thread ID is 984
2023-08-22T23:25:34.331Z,1692746734.331 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:34.331Z,1692746734.331 [DAT](INFO): Powering down
2023-08-22T23:25:34.332Z,1692746734.332 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:34.343Z,1692746734.343 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-22T23:25:34.343Z,1692746734.343 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:34.343Z,1692746734.343 [Radio_Surface](INFO): Join timeout helper Thread ID is 985
2023-08-22T23:25:34.695Z,1692746734.695 [Radio_Surface](INFO): Powering down
2023-08-22T23:25:34.696Z,1692746734.696 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:34.696Z,1692746734.696 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:34.704Z,1692746734.704 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-22T23:25:34.704Z,1692746734.704 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:34.704Z,1692746734.704 [Onboard](INFO): Join timeout helper Thread ID is 986
2023-08-22T23:25:35.135Z,1692746735.135 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:35.135Z,1692746735.135 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.148Z,1692746735.148 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-22T23:25:35.148Z,1692746735.148 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.148Z,1692746735.148 [DataOverHttps](INFO): Join timeout helper Thread ID is 987
2023-08-22T23:25:35.635Z,1692746735.635 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:35.636Z,1692746735.636 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.647Z,1692746735.647 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-22T23:25:35.648Z,1692746735.648 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.648Z,1692746735.648 [logger](INFO): Join timeout helper Thread ID is 988
2023-08-22T23:25:35.675Z,1692746735.675 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:35.675Z,1692746735.675 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.687Z,1692746735.687 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-22T23:25:35.687Z,1692746735.687 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.688Z,1692746735.688 [CommandLine](INFO): Join timeout helper Thread ID is 989
2023-08-22T23:25:35.775Z,1692746735.775 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:35.775Z,1692746735.775 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.777Z,1692746735.777 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-22T23:25:35.777Z,1692746735.777 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.777Z,1692746735.777 [CommandExec](INFO): Join timeout helper Thread ID is 990
2023-08-22T23:25:35.784Z,1692746735.784 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-22T23:25:35.784Z,1692746735.784 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.784Z,1692746735.784 [controlThread](INFO): Join timeout helper Thread ID is 991
2023-08-22T23:25:35.803Z,1692746735.803 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-22T23:25:35.803Z,1692746735.803 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-22T23:25:35.805Z,1692746735.805 [NAL9602](INFO): Powering down
2023-08-22T23:25:35.806Z,1692746735.806 [Rowe_600](INFO): Powering down
2023-08-22T23:25:35.809Z,1692746735.809 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-22T23:25:35.810Z,1692746735.810 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-22T23:25:35.811Z,1692746735.811 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-22T23:25:35.812Z,1692746735.812 [MissionManager](INFO): Uninitializing Mission Default
2023-08-22T23:25:35.812Z,1692746735.812 [Default] Stopped
2023-08-22T23:25:35.812Z,1692746735.812 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-22T23:25:35.812Z,1692746735.812 [Default:B.GoToSurface] Stopped
2023-08-22T23:25:35.812Z,1692746735.812 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-22T23:25:35.812Z,1692746735.812 [Default:CheckIn] Stopped
2023-08-22T23:25:35.812Z,1692746735.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-22T23:25:35.812Z,1692746735.812 [Default:CheckIn:Read_Iridium] Stopped
2023-08-22T23:25:35.815Z,1692746735.815 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-22T23:25:35.816Z,1692746735.816 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-22T23:25:35.816Z,1692746735.816 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-22T23:25:35.816Z,1692746735.816 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-22T23:25:35.817Z,1692746735.817 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-22T23:25:35.817Z,1692746735.817 [BuoyancyServo](INFO): Powering down
2023-08-22T23:25:35.831Z,1692746735.831 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-22T23:25:35.831Z,1692746735.831 [ElevatorServo](INFO): Powering down
2023-08-22T23:25:35.832Z,1692746735.832 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-22T23:25:35.832Z,1692746735.832 [MassServo](INFO): Powering down
2023-08-22T23:25:35.833Z,1692746735.833 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-22T23:25:35.833Z,1692746735.833 [RudderServo](INFO): Powering down
2023-08-22T23:25:35.834Z,1692746735.834 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-22T23:25:35.834Z,1692746735.834 [ThrusterHE](INFO): Powering down
2023-08-22T23:25:35.835Z,1692746735.835 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-22T23:25:35.836Z,1692746735.836 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-22T23:25:35.836Z,1692746735.836 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-22T23:25:35.836Z,1692746735.836 [CBIT](DEBUG): Powering off loads.
2023-08-22T23:25:35.847Z,1692746735.847 [CBIT](DEBUG): Disabling WDT.
2023-08-22T23:25:35.859Z,1692746735.859 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-22T23:25:35.860Z,1692746735.860 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.887Z,1692746735.887 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.894Z,1692746735.894 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.896Z,1692746735.896 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:35.902Z,1692746735.902 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:36.038Z,1692746736.038 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:36.044Z,1692746736.044 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:36.053Z,1692746736.053 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-22T23:25:36.100Z,1692746736.100 [logger ThreadHandler](INFO): Thread cancelled.