2023-03-31T20:17:21.173Z,1680293841.173 [Supervisor](DEBUG): Initializing supervisor.
2023-03-31T20:17:21.178Z,1680293841.178 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-03-31T20:17:21.178Z,1680293841.178 [SyncHandler](INFO): Protected caller Thread ID is 1596
2023-03-31T20:17:21.179Z,1680293841.179 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-03-31T20:17:21.180Z,1680293841.180 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-03-31T20:17:21.180Z,1680293841.180 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1597
2023-03-31T20:17:21.184Z,1680293841.184 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-03-31T20:17:21.202Z,1680293841.202 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-03-31T20:17:21.203Z,1680293841.203 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-03-31T20:17:21.203Z,1680293841.203 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1598
2023-03-31T20:17:21.207Z,1680293841.207 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-03-31T20:17:21.208Z,1680293841.208 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-03-31T20:17:21.209Z,1680293841.209 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1599
2023-03-31T20:17:21.211Z,1680293841.211 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-03-31T20:17:21.211Z,1680293841.211 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-03-31T20:17:21.212Z,1680293841.212 [logger ThreadHandler](INFO): Protected caller Thread ID is 1600
2023-03-31T20:17:21.215Z,1680293841.215 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-03-31T20:17:21.216Z,1680293841.216 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-03-31T20:17:21.218Z,1680293841.218 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-03-31T20:17:21.610Z,1680293841.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-03-31T20:17:21.611Z,1680293841.611 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-03-31T20:17:22.281Z,1680293842.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-03-31T20:17:22.282Z,1680293842.282 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-03-31T20:17:22.390Z,1680293842.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-03-31T20:17:22.390Z,1680293842.390 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-03-31T20:17:22.869Z,1680293842.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-03-31T20:17:22.870Z,1680293842.870 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-03-31T20:17:22.967Z,1680293842.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-03-31T20:17:22.968Z,1680293842.968 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-03-31T20:17:23.049Z,1680293843.049 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-03-31T20:17:23.442Z,1680293843.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-03-31T20:17:23.442Z,1680293843.442 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-03-31T20:17:23.658Z,1680293843.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-03-31T20:17:23.659Z,1680293843.659 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-03-31T20:17:23.795Z,1680293843.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-03-31T20:17:23.795Z,1680293843.795 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-03-31T20:17:24.025Z,1680293844.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-03-31T20:17:24.026Z,1680293844.026 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-03-31T20:17:24.534Z,1680293844.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-03-31T20:17:24.535Z,1680293844.535 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-03-31T20:17:24.900Z,1680293844.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-03-31T20:17:24.901Z,1680293844.901 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-03-31T20:17:24.981Z,1680293844.981 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-03-31T20:17:25.430Z,1680293845.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-03-31T20:17:25.430Z,1680293845.430 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-03-31T20:17:26.162Z,1680293846.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-03-31T20:17:26.162Z,1680293846.162 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-03-31T20:17:26.451Z,1680293846.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-03-31T20:17:26.453Z,1680293846.453 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2023-03-31T20:17:26.454Z,1680293846.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2023-03-31T20:17:26.566Z,1680293846.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2023-03-31T20:17:26.713Z,1680293846.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2023-03-31T20:17:26.799Z,1680293846.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2023-03-31T20:17:26.883Z,1680293846.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2023-03-31T20:17:26.997Z,1680293846.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2023-03-31T20:17:27.168Z,1680293847.168 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2023-03-31T20:17:27.430Z,1680293847.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-03-31T20:17:27.430Z,1680293847.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2023-03-31T20:17:27.562Z,1680293847.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2023-03-31T20:17:27.654Z,1680293847.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2023-03-31T20:17:27.759Z,1680293847.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2023-03-31T20:17:27.859Z,1680293847.859 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-03-31T20:17:27.879Z,1680293847.879 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-03-31T20:17:27.996Z,1680293847.996 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-03-31T20:17:27.997Z,1680293847.997 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-03-31T20:17:28.010Z,1680293848.010 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-03-31T20:17:28.011Z,1680293848.011 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-03-31T20:17:28.076Z,1680293848.076 [DepthRateCalculator] Loaded
2023-03-31T20:17:28.076Z,1680293848.076 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-03-31T20:17:28.082Z,1680293848.082 [PitchRateCalculator] Loaded
2023-03-31T20:17:28.082Z,1680293848.082 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-03-31T20:17:28.093Z,1680293848.093 [SpeedCalculator] Loaded
2023-03-31T20:17:28.093Z,1680293848.093 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-03-31T20:17:28.108Z,1680293848.108 [VerticalHomogeneityIndexCalculator] Loaded
2023-03-31T20:17:28.108Z,1680293848.108 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-03-31T20:17:28.113Z,1680293848.113 [YawRateCalculator] Loaded
2023-03-31T20:17:28.113Z,1680293848.113 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-03-31T20:17:28.135Z,1680293848.135 [ElevatorOffsetCalculator] Loaded
2023-03-31T20:17:28.135Z,1680293848.135 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-03-31T20:17:28.136Z,1680293848.136 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-03-31T20:17:28.136Z,1680293848.136 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-03-31T20:17:28.200Z,1680293848.200 [VerticalControl](DEBUG): Construct VerticalControl.
2023-03-31T20:17:28.256Z,1680293848.256 [VerticalControl] Loaded
2023-03-31T20:17:28.257Z,1680293848.257 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-03-31T20:17:28.259Z,1680293848.259 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-03-31T20:17:28.300Z,1680293848.300 [HorizontalControl] Loaded
2023-03-31T20:17:28.301Z,1680293848.301 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-03-31T20:17:28.303Z,1680293848.303 [SpeedControl](DEBUG): Construct SpeedControl.
2023-03-31T20:17:28.306Z,1680293848.306 [SpeedControl] Loaded
2023-03-31T20:17:28.306Z,1680293848.306 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-03-31T20:17:28.309Z,1680293848.309 [LoopControl](DEBUG): Construct LoopControl.
2023-03-31T20:17:28.309Z,1680293848.309 [LoopControl] Loaded
2023-03-31T20:17:28.309Z,1680293848.309 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-03-31T20:17:28.310Z,1680293848.310 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-03-31T20:17:28.310Z,1680293848.310 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-03-31T20:17:28.440Z,1680293848.440 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-03-31T20:17:28.440Z,1680293848.440 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-03-31T20:17:28.454Z,1680293848.454 [NavChart] Loaded
2023-03-31T20:17:28.454Z,1680293848.454 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-03-31T20:17:28.460Z,1680293848.460 [UniversalFixResidualReporter] Loaded
2023-03-31T20:17:28.460Z,1680293848.460 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-03-31T20:17:28.460Z,1680293848.460 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-03-31T20:17:28.461Z,1680293848.461 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-03-31T20:17:28.637Z,1680293848.637 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-03-31T20:17:28.637Z,1680293848.637 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-03-31T20:17:29.207Z,1680293849.207 [AHRS_M2] Loaded
2023-03-31T20:17:29.207Z,1680293849.207 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-03-31T20:17:29.243Z,1680293849.243 [BackseatComponent] Loaded
2023-03-31T20:17:29.243Z,1680293849.243 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-03-31T20:17:29.244Z,1680293849.244 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-03-31T20:17:29.244Z,1680293849.244 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1682
2023-03-31T20:17:29.247Z,1680293849.247 [LcmUniversalReporter] Loaded
2023-03-31T20:17:29.247Z,1680293849.247 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-03-31T20:17:30.072Z,1680293850.072 [BPC1] Loaded
2023-03-31T20:17:30.073Z,1680293850.073 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-03-31T20:17:30.145Z,1680293850.145 [DataOverHttps] Loaded
2023-03-31T20:17:30.145Z,1680293850.145 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-03-31T20:17:30.146Z,1680293850.146 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-03-31T20:17:30.147Z,1680293850.147 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1683
2023-03-31T20:17:30.167Z,1680293850.167 [Depth_Keller] Loaded
2023-03-31T20:17:30.167Z,1680293850.167 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-03-31T20:17:30.206Z,1680293850.206 [DVL_micro] Loaded
2023-03-31T20:17:30.206Z,1680293850.206 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-03-31T20:17:30.268Z,1680293850.268 [NAL9602] Loaded
2023-03-31T20:17:30.268Z,1680293850.268 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-03-31T20:17:30.298Z,1680293850.298 [Onboard] Loaded
2023-03-31T20:17:30.298Z,1680293850.298 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-03-31T20:17:30.299Z,1680293850.299 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-03-31T20:17:30.299Z,1680293850.299 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1684
2023-03-31T20:17:30.311Z,1680293850.311 [Power24vConverter] Loaded
2023-03-31T20:17:30.312Z,1680293850.312 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-03-31T20:17:30.325Z,1680293850.325 [Radio_Surface] Loaded
2023-03-31T20:17:30.325Z,1680293850.325 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-03-31T20:17:30.326Z,1680293850.326 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4098D4E0
2023-03-31T20:17:30.326Z,1680293850.326 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1685
2023-03-31T20:17:30.414Z,1680293850.414 [DATMMP] Loaded
2023-03-31T20:17:30.416Z,1680293850.416 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-03-31T20:17:30.424Z,1680293850.424 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409BE4E0
2023-03-31T20:17:30.424Z,1680293850.424 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1686
2023-03-31T20:17:30.463Z,1680293850.463 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-03-31T20:17:30.472Z,1680293850.472 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-03-31T20:17:31.053Z,1680293851.053 [BuoyancyServo] Loaded
2023-03-31T20:17:31.053Z,1680293851.053 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-03-31T20:17:31.073Z,1680293851.073 [ElevatorServo] Loaded
2023-03-31T20:17:31.073Z,1680293851.073 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-03-31T20:17:31.093Z,1680293851.093 [MassServo] Loaded
2023-03-31T20:17:31.094Z,1680293851.094 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-03-31T20:17:31.113Z,1680293851.113 [RudderServo] Loaded
2023-03-31T20:17:31.113Z,1680293851.113 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-03-31T20:17:31.127Z,1680293851.127 [ThrusterHE] Loaded
2023-03-31T20:17:31.128Z,1680293851.128 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-03-31T20:17:31.128Z,1680293851.128 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-03-31T20:17:31.129Z,1680293851.129 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-03-31T20:17:31.219Z,1680293851.219 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.050012 degC
2023-03-31T20:17:31.219Z,1680293851.219 [StratificationFrontDetector](INFO): (re)initializing
2023-03-31T20:17:31.219Z,1680293851.219 [StratificationFrontDetector] Loaded
2023-03-31T20:17:31.219Z,1680293851.219 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-03-31T20:17:31.220Z,1680293851.220 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-03-31T20:17:31.220Z,1680293851.220 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-03-31T20:17:31.258Z,1680293851.258 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-03-31T20:17:31.258Z,1680293851.258 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-03-31T20:17:31.601Z,1680293851.601 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-03-31T20:17:31.601Z,1680293851.601 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-03-31T20:17:31.804Z,1680293851.804 [CTD_Seabird] Loaded
2023-03-31T20:17:31.804Z,1680293851.804 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-03-31T20:17:31.805Z,1680293851.805 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B484E0
2023-03-31T20:17:31.805Z,1680293851.805 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1687
2023-03-31T20:17:31.840Z,1680293851.840 [ESPComponent] Loaded
2023-03-31T20:17:31.840Z,1680293851.840 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-03-31T20:17:31.860Z,1680293851.860 [PAR_Licor] Loaded
2023-03-31T20:17:31.860Z,1680293851.860 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-03-31T20:17:31.891Z,1680293851.891 [WetLabsBB2FL] Loaded
2023-03-31T20:17:31.892Z,1680293851.892 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-03-31T20:17:31.893Z,1680293851.893 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B784E0
2023-03-31T20:17:31.893Z,1680293851.893 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1688
2023-03-31T20:17:31.894Z,1680293851.894 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-03-31T20:17:31.894Z,1680293851.894 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-03-31T20:17:32.057Z,1680293852.057 [SBIT](DEBUG): Construct Startup Built In Test.
2023-03-31T20:17:32.066Z,1680293852.066 [SBIT] Loaded
2023-03-31T20:17:32.066Z,1680293852.066 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-03-31T20:17:32.069Z,1680293852.069 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-03-31T20:17:32.083Z,1680293852.083 [IBIT] Loaded
2023-03-31T20:17:32.083Z,1680293852.083 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-03-31T20:17:32.089Z,1680293852.089 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-03-31T20:17:32.196Z,1680293852.196 [CBIT] Loaded
2023-03-31T20:17:32.197Z,1680293852.197 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-03-31T20:17:32.197Z,1680293852.197 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-03-31T20:17:32.203Z,1680293852.203 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-03-31T20:17:32.206Z,1680293852.206 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-03-31T20:17:32.218Z,1680293852.218 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-03-31T20:17:32.219Z,1680293852.219 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BE64E0
2023-03-31T20:17:32.219Z,1680293852.219 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1689
2023-03-31T20:17:32.223Z,1680293852.223 [Supervisor](INFO): Main Thread ID is 828
2023-03-31T20:17:32.224Z,1680293852.224 [Supervisor](DEBUG): Running supervisor.
2023-03-31T20:17:32.224Z,1680293852.224 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1690
2023-03-31T20:17:32.225Z,1680293852.225 [CommandExec](INFO): Initializing the command executive.
2023-03-31T20:17:32.226Z,1680293852.226 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1691
2023-03-31T20:17:32.228Z,1680293852.228 [controlThread ThreadHandler](INFO): Handler Thread ID is 1692
2023-03-31T20:17:32.229Z,1680293852.229 [controlThread](DEBUG): Initializing ControlThread
2023-03-31T20:17:32.230Z,1680293852.230 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-03-31T20:17:32.230Z,1680293852.230 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-03-31T20:17:32.230Z,1680293852.230 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-03-31T20:17:32.231Z,1680293852.231 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-03-31T20:17:32.231Z,1680293852.231 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-03-31T20:17:32.232Z,1680293852.232 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-03-31T20:17:32.232Z,1680293852.232 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-03-31T20:17:32.234Z,1680293852.234 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-03-31T20:17:32.235Z,1680293852.235 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-03-31T20:17:32.235Z,1680293852.235 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-03-31T20:17:32.236Z,1680293852.236 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-03-31T20:17:32.237Z,1680293852.237 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-03-31T20:17:32.245Z,1680293852.245 [SBIT](INFO): Initialize SBIT Component.
2023-03-31T20:17:32.245Z,1680293852.245 [SBIT](IMPORTANT): git: 2023-01-24-37-g2cb96bfa7
2023-03-31T20:17:32.246Z,1680293852.246 [SBIT](INFO): git hash: 2cb96bfa7ba90f0279afde19b052484c1354617e
2023-03-31T20:17:32.246Z,1680293852.246 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-03-31T20:17:32.247Z,1680293852.247 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-03-31T20:17:32.248Z,1680293852.248 [SBIT](INFO): Beginning SBIT in 131.000000 seconds.
2023-03-31T20:17:32.249Z,1680293852.249 [IBIT](INFO): Initialize IBIT Component.
2023-03-31T20:17:32.250Z,1680293852.250 [CBIT](DEBUG): Initialize CBIT Component.
2023-03-31T20:17:32.250Z,1680293852.250 [logger ThreadHandler](INFO): Handler Thread ID is 1693
2023-03-31T20:17:32.261Z,1680293852.261 [CBIT](DEBUG): Initialized mux pins.
2023-03-31T20:17:32.261Z,1680293852.261 [CBIT](DEBUG): Initializing the watchdog timer.
2023-03-31T20:17:32.269Z,1680293852.269 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1694
2023-03-31T20:17:32.281Z,1680293852.281 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1695
2023-03-31T20:17:32.282Z,1680293852.282 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-03-31T20:17:32.285Z,1680293852.285 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2023-03-31T20:17:32.285Z,1680293852.285 [CBIT](DEBUG): Initializing heartbeat.
2023-03-31T20:17:32.293Z,1680293852.293 [Onboard ThreadHandler](INFO): Handler Thread ID is 1696
2023-03-31T20:17:32.323Z,1680293852.323 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1697
2023-03-31T20:17:32.333Z,1680293852.333 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1698
2023-03-31T20:17:32.334Z,1680293852.334 [DATMMP](INFO): Start
2023-03-31T20:17:32.334Z,1680293852.334 [DATMMP](INFO): Powering up
2023-03-31T20:17:32.334Z,1680293852.334 [DATMMP](DEBUG): Initializing DATMMP.
2023-03-31T20:17:32.338Z,1680293852.338 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1700
2023-03-31T20:17:32.339Z,1680293852.339 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-03-31T20:17:32.342Z,1680293852.342 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1702
2023-03-31T20:17:32.345Z,1680293852.345 [WetLabsBB2FL](INFO): Powering up
2023-03-31T20:17:32.346Z,1680293852.346 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1704
2023-03-31T20:17:32.353Z,1680293852.353 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-03-31T20:17:32.354Z,1680293852.354 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-03-31T20:17:32.354Z,1680293852.354 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-03-31T20:17:32.354Z,1680293852.354 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-03-31T20:17:32.354Z,1680293852.354 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-03-31T20:17:32.354Z,1680293852.354 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-03-31T20:17:32.354Z,1680293852.354 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-03-31T20:17:32.355Z,1680293852.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-03-31T20:17:32.357Z,1680293852.357 [CBIT](DEBUG): Deactivating GF circuits.
2023-03-31T20:17:32.357Z,1680293852.357 [CBIT](DEBUG): Deactivating emergency mode.
2023-03-31T20:17:32.393Z,1680293852.393 [CBIT](DEBUG): Backplane powered.
2023-03-31T20:17:32.394Z,1680293852.394 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-03-31T20:17:32.407Z,1680293852.407 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-31T20:17:32.447Z,1680293852.447 [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-03-31T20:17:32.447Z,1680293852.447 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-03-31T20:17:32.515Z,1680293852.515 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-03-31T20:17:32.516Z,1680293852.516 [Default:A.Wait](DEBUG): Construct Wait.
2023-03-31T20:17:32.518Z,1680293852.518 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-31T20:17:32.563Z,1680293852.563 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-31T20:17:32.565Z,1680293852.565 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-31T20:17:32.588Z,1680293852.588 [Default:E.Execute](DEBUG): Construct Execute.
2023-03-31T20:17:32.591Z,1680293852.591 [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-03-31T20:17:32.609Z,1680293852.609 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-03-31T20:17:32.615Z,1680293852.615 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-03-31T20:17:32.694Z,1680293852.694 [Power24vConverter](INFO): Powering up.
2023-03-31T20:17:32.725Z,1680293852.725 [Radio_Surface](INFO): Powering up
2023-03-31T20:17:32.748Z,1680293852.748 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-03-31T20:17:32.757Z,1680293852.757 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-03-31T20:17:32.758Z,1680293852.758 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-03-31T20:17:32.765Z,1680293852.765 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-03-31T20:17:32.766Z,1680293852.766 [MassServo](DEBUG): Initializing EZServoServo.
2023-03-31T20:17:32.773Z,1680293852.773 [MassServo](DEBUG): Initializing MassServo.
2023-03-31T20:17:32.774Z,1680293852.774 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-31T20:17:32.781Z,1680293852.781 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-31T20:17:32.782Z,1680293852.782 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-03-31T20:17:32.789Z,1680293852.789 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-03-31T20:17:32.791Z,1680293852.791 [CommandExec](FAULT): Scheduling is paused
2023-03-31T20:17:32.791Z,1680293852.791 [CBIT](INFO): Critical error at 20230331T201732
2023-03-31T20:17:32.791Z,1680293852.791 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-03-31T20:17:34.957Z,1680293854.957 [WetLabsBB2FL](INFO): Powering down
2023-03-31T20:17:38.350Z,1680293858.350 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-03-31T20:17:39.369Z,1680293859.369 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-03-31T20:18:00.514Z,1680293880.514 [NAL9602](INFO): Powering up NAL9602
2023-03-31T20:18:11.411Z,1680293891.411 [NAL9602](INFO): NAL9602 initialized
2023-03-31T20:18:32.363Z,1680293912.363 [DATMMP](FAULT): failed to initialize, no bytes available on serial interface
2023-03-31T20:18:32.363Z,1680293912.363 [DATMMP] Communications Fault, FailCount= 1
2023-03-31T20:18:32.363Z,1680293912.363 [DATMMP](ERROR): Communications Fault
2023-03-31T20:18:32.439Z,1680293912.439 [CBIT](ERROR): Communications Fault in component: DATMMP
2023-03-31T20:18:32.765Z,1680293912.765 [DATMMP](INFO): Stop
2023-03-31T20:18:32.765Z,1680293912.765 [DATMMP](INFO): uninitialize
2023-03-31T20:18:32.765Z,1680293912.765 [DATMMP](INFO): Powering down
2023-03-31T20:18:33.242Z,1680293913.242 [DATMMP](INFO): Stopping
2023-03-31T20:18:33.649Z,1680293913.649 [DATMMP](INFO): Stopped
2023-03-31T20:18:33.712Z,1680293913.712 [CBIT](INFO): Clearing failed state for component DATMMP
2023-03-31T20:18:33.712Z,1680293913.712 [DATMMP] No Fault, FailCount= 1
2023-03-31T20:18:34.053Z,1680293914.053 [DATMMP](INFO): Start
2023-03-31T20:18:34.305Z,1680293914.305 [DATMMP](INFO): Start
2023-03-31T20:18:34.557Z,1680293914.557 [DATMMP](INFO): Start
2023-03-31T20:18:34.809Z,1680293914.809 [DATMMP](INFO): Start
2023-03-31T20:18:35.061Z,1680293915.061 [DATMMP](INFO): Start
2023-03-31T20:18:35.313Z,1680293915.313 [DATMMP](INFO): Start
2023-03-31T20:18:35.565Z,1680293915.565 [DATMMP](INFO): Start
2023-03-31T20:18:35.817Z,1680293915.817 [DATMMP](INFO): Start
2023-03-31T20:18:35.817Z,1680293915.817 [DATMMP](INFO): Powering up
2023-03-31T20:18:35.817Z,1680293915.817 [DATMMP](DEBUG): Initializing DATMMP.
2023-03-31T20:19:36.053Z,1680293976.053 [DATMMP](FAULT): failed to initialize, no bytes available on serial interface
2023-03-31T20:19:36.053Z,1680293976.053 [DATMMP] Communications Fault, FailCount= 2
2023-03-31T20:19:36.053Z,1680293976.053 [DATMMP](ERROR): Communications Fault
2023-03-31T20:19:36.296Z,1680293976.296 [CBIT](ERROR): Communications Fault in component: DATMMP
2023-03-31T20:19:36.457Z,1680293976.457 [DATMMP](INFO): Stop
2023-03-31T20:19:36.457Z,1680293976.457 [DATMMP](INFO): uninitialize
2023-03-31T20:19:36.457Z,1680293976.457 [DATMMP](INFO): Powering down
2023-03-31T20:19:36.933Z,1680293976.933 [DATMMP](INFO): Stopping
2023-03-31T20:19:37.337Z,1680293977.337 [DATMMP](INFO): Stopped
2023-03-31T20:19:37.500Z,1680293977.500 [CBIT](INFO): Clearing failed state for component DATMMP
2023-03-31T20:19:37.500Z,1680293977.500 [DATMMP] No Fault, FailCount= 2
2023-03-31T20:19:37.741Z,1680293977.741 [DATMMP](INFO): Start
2023-03-31T20:19:37.993Z,1680293977.993 [DATMMP](INFO): Start
2023-03-31T20:19:38.245Z,1680293978.245 [DATMMP](INFO): Start
2023-03-31T20:19:38.497Z,1680293978.497 [DATMMP](INFO): Start
2023-03-31T20:19:38.749Z,1680293978.749 [DATMMP](INFO): Start
2023-03-31T20:19:39.001Z,1680293979.001 [DATMMP](INFO): Start
2023-03-31T20:19:39.253Z,1680293979.253 [DATMMP](INFO): Start
2023-03-31T20:19:39.509Z,1680293979.509 [DATMMP](INFO): Start
2023-03-31T20:19:39.509Z,1680293979.509 [DATMMP](INFO): Powering up
2023-03-31T20:19:39.509Z,1680293979.509 [DATMMP](DEBUG): Initializing DATMMP.
2023-03-31T20:19:43.940Z,1680293983.940 [SBIT](IMPORTANT): Beginning Startup BIT
2023-03-31T20:19:43.944Z,1680293983.944 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-31T20:19:47.580Z,1680293987.580 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-31T20:19:47.580Z,1680293987.580 [DVL_micro](ERROR): Failed to parse:
:BI,+01569,-0195,+00000,I0000000.00,+00000000.00,+00000000.00,9999.99,000.00
2023-03-31T20:19:54.961Z,1680293994.961 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012985
CHAN A1 (24V): -0.002173
CHAN A2 (12V): -0.000852
CHAN A3 (5V): 0.000342
CHAN B0 (3.3V): 0.000278
CHAN B1 (3.15aV): 0.000101
CHAN B2 (3.15bV): 0.000409
CHAN B3 (GND): 0.001329
OPEN: 0.004293
Full Scale: +/- 1 mA
2023-03-31T20:20:00.203Z,1680294000.203 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004497
2023-03-31T20:20:04.932Z,1680294004.932 [BPC1](ERROR): BPC1A: No match for serial number 0BE9 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2023-03-31T20:20:11.525Z,1680294011.525 [DATMMP](INFO): DAT read: Acoustic Wakeup

2023-03-31T20:20:11.527Z,1680294011.527 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-03-31T20:20:11.527Z,1680294011.527 [DATMMP](INFO): commRate: 800
2023-03-31T20:20:12.029Z,1680294012.029 [DATMMP](INFO): DAT read: Rx Time:20:20:14.0333

2023-03-31T20:20:12.533Z,1680294012.533 [DATMMP](INFO): DAT read: 

2023-03-31T20:20:12.534Z,1680294012.534 [DATMMP](INFO): DAT read: $Packet for address 0

2023-03-31T20:20:12.534Z,1680294012.534 [DATMMP](INFO): DAT read:  [00]<[09] DATA PACKET

2023-03-31T20:20:12.535Z,1680294012.535 [DATMMP](INFO): DAT read: Lowpower

2023-03-31T20:20:13.593Z,1680294013.593 [DATMMP](INFO): entering command mode
2023-03-31T20:20:13.793Z,1680294013.793 [DATMMP](INFO): DAT read: UART Wakeup

2023-03-31T20:20:13.794Z,1680294013.794 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-03-31T20:20:13.795Z,1680294013.795 [DATMMP](INFO): commRate: 800
2023-03-31T20:20:13.795Z,1680294013.795 [DATMMP](INFO): DAT read: 

2023-03-31T20:20:13.795Z,1680294013.795 [DATMMP](INFO): DAT read: user:8>

2023-03-31T20:20:13.796Z,1680294013.796 [DATMMP](DEBUG): read user prompt 8: user:8>

2023-03-31T20:20:13.796Z,1680294013.796 [DATMMP](INFO): entering MMP mode
2023-03-31T20:20:14.045Z,1680294014.045 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:14.047Z,1680294014.047 [DATMMP](INFO): status rx: 	 x1
2023-03-31T20:20:14.047Z,1680294014.047 [DATMMP](INFO): MMP status: 1: started
2023-03-31T20:20:14.047Z,1680294014.047 [DATMMP](INFO): Received message type: status
2023-03-31T20:20:14.048Z,1680294014.048 [DATMMP](INFO): Handled
2023-03-31T20:20:14.298Z,1680294014.298 [DATMMP](INFO): Sent Tx Power Config 8
2023-03-31T20:20:14.298Z,1680294014.298 [DATMMP](INFO): Setting local address: 8
2023-03-31T20:20:14.300Z,1680294014.300 [DATMMP](INFO): Setting time to: 20:20:14 And date to:3/31/2023
2023-03-31T20:20:14.549Z,1680294014.549 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:14.550Z,1680294014.550 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-03-31T20:20:14.550Z,1680294014.550 [DATMMP](INFO): Received message type: mdmpower
2023-03-31T20:20:14.550Z,1680294014.550 [DATMMP](INFO): Handled
2023-03-31T20:20:14.801Z,1680294014.801 [DATMMP](INFO): GSXN notify for xid: 10
2023-03-31T20:20:14.802Z,1680294014.802 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-03-31T20:20:14.802Z,1680294014.802 [DATMMP](INFO): Received message type: sreg_txpower
2023-03-31T20:20:14.802Z,1680294014.802 [DATMMP](INFO): Match for : sreg_txpower
2023-03-31T20:20:14.802Z,1680294014.802 [DATMMP](INFO): ACK
2023-03-31T20:20:15.053Z,1680294015.053 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:15.054Z,1680294015.054 [DATMMP](INFO): mdmlocal rx: 	 x8	 x0	 x0	 x0
2023-03-31T20:20:15.054Z,1680294015.054 [DATMMP](INFO): Received message type: mdmlocal
2023-03-31T20:20:15.054Z,1680294015.054 [DATMMP](INFO): Handled
2023-03-31T20:20:15.305Z,1680294015.305 [DATMMP](INFO): GSXN notify for xid: 11
2023-03-31T20:20:15.306Z,1680294015.306 [DATMMP](INFO): sreg_locaddr rx: 	 x8
2023-03-31T20:20:15.306Z,1680294015.306 [DATMMP](INFO): Received message type: sreg_locaddr
2023-03-31T20:20:15.306Z,1680294015.306 [DATMMP](INFO): Match for : sreg_locaddr
2023-03-31T20:20:15.306Z,1680294015.306 [DATMMP](INFO): ACK
2023-03-31T20:20:15.557Z,1680294015.557 [DATMMP](INFO): GSXN notify for xid: 12
2023-03-31T20:20:15.559Z,1680294015.559 [DATMMP](INFO): timedate rx: 	 x0	 x14	 x14	 xE	 x2	 x1F	 x7B	 x0
2023-03-31T20:20:15.559Z,1680294015.559 [DATMMP](INFO): Received message type: timedate
2023-03-31T20:20:15.559Z,1680294015.559 [DATMMP](INFO): Match for : timedate
2023-03-31T20:20:15.559Z,1680294015.559 [DATMMP](INFO): ACK
2023-03-31T20:20:15.809Z,1680294015.809 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:15.810Z,1680294015.810 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-03-31T20:20:15.810Z,1680294015.810 [DATMMP](INFO): Received message type: sys_verb
2023-03-31T20:20:15.810Z,1680294015.810 [DATMMP](INFO): Handled
2023-03-31T20:20:16.061Z,1680294016.061 [DATMMP](INFO): GSXN notify for xid: 13
2023-03-31T20:20:16.062Z,1680294016.062 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-03-31T20:20:16.062Z,1680294016.062 [DATMMP](INFO): Received message type: sreg_verbosity
2023-03-31T20:20:16.062Z,1680294016.062 [DATMMP](INFO): Match for : sreg_verbosity
2023-03-31T20:20:16.062Z,1680294016.062 [DATMMP](INFO): ACK
2023-03-31T20:20:16.313Z,1680294016.313 [DATMMP](INFO): GSXN notify for xid: 14
2023-03-31T20:20:16.314Z,1680294016.314 [DATMMP](INFO): version rx: 	 x2	 x5
2023-03-31T20:20:16.314Z,1680294016.314 [DATMMP](INFO): DatMMPVersion: 2.5
2023-03-31T20:20:16.314Z,1680294016.314 [DATMMP](INFO): Received message type: version
2023-03-31T20:20:16.314Z,1680294016.314 [DATMMP](INFO): Match for : version
2023-03-31T20:20:16.314Z,1680294016.314 [DATMMP](INFO): ACK
2023-03-31T20:20:16.315Z,1680294016.315 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-03-31T20:20:19.131Z,1680294019.131 [BPC1](ERROR): BPC1B: No match for serial number 0C0C in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-03-31T20:20:19.477Z,1680294019.477 [BPC1](ERROR): BPC1A: No match for serial number 0BE9 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2023-03-31T20:20:19.489Z,1680294019.489 [BPC1](FAULT): Failed to parse data from all battery packs.
2023-03-31T20:20:19.489Z,1680294019.489 [BPC1] Data Fault, FailCount= 1
2023-03-31T20:20:19.489Z,1680294019.489 [BPC1](ERROR): Data Fault
2023-03-31T20:20:19.516Z,1680294019.516 [CBIT](ERROR): Data Fault in component: BPC1
2023-03-31T20:20:28.417Z,1680294028.417 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:28.418Z,1680294028.418 [DATMMP](INFO): doppler rx: 	 x0
2023-03-31T20:20:28.418Z,1680294028.418 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:20:28.418Z,1680294028.418 [DATMMP](INFO): Handled
2023-03-31T20:20:28.669Z,1680294028.669 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:28.670Z,1680294028.670 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x14	 x1B	 x1E08
2023-03-31T20:20:28.671Z,1680294028.671 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294038.081920
2023-03-31T20:20:28.671Z,1680294028.671 [DATMMP](INFO): Rx ping set to:1680294028.670243
2023-03-31T20:20:28.671Z,1680294028.671 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:20:28.672Z,1680294028.672 [DATMMP](INFO): Handled
2023-03-31T20:20:29.173Z,1680294029.173 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:29.174Z,1680294029.174 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:20:29.174Z,1680294029.174 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:20:29.174Z,1680294029.174 [DATMMP](INFO): Handled
2023-03-31T20:20:29.426Z,1680294029.426 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:29.427Z,1680294029.427 [DATMMP](INFO): directional_debug rx: 	 x1FE	 xFEC3	 x39	 x35	 x45B0	 x4AA1	 x4542	 x4CF3
2023-03-31T20:20:29.428Z,1680294029.428 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:20:29.428Z,1680294029.428 [DATMMP](INFO): Handled
2023-03-31T20:20:29.677Z,1680294029.677 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:29.679Z,1680294029.679 [DATMMP](INFO): directional rx: 	 x1E4	 xFEC2	 x3DE	 x13D	 x953	 x236	 x0	 xD59	 xFFFA	 xFC75	 x27B5966	 x-43569E0
2023-03-31T20:20:29.681Z,1680294029.681 [DATMMP](INFO): Received message type: directional
2023-03-31T20:20:29.681Z,1680294029.681 [DATMMP](INFO): Handled
2023-03-31T20:20:29.929Z,1680294029.929 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:29.930Z,1680294029.930 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 xB	 x0	 x0	 x9	 x0
2023-03-31T20:20:29.931Z,1680294029.931 [DATMMP](INFO): DATA Src=9, Dst=0
2023-03-31T20:20:29.931Z,1680294029.931 [DATMMP](INFO): Received message type: remote_header
2023-03-31T20:20:29.931Z,1680294029.931 [DATMMP](INFO): Handled
2023-03-31T20:20:30.181Z,1680294030.181 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:30.183Z,1680294030.183 [DATMMP](INFO): acstats rx: 	 x9	 x5	 x0	 x183	 x3B	 x53	 xB5	 xB5	 xD	 xFFFF	 x0	 x41	 x0	 x0
2023-03-31T20:20:30.183Z,1680294030.183 [DATMMP](INFO): Received message type: acstats
2023-03-31T20:20:30.183Z,1680294030.183 [DATMMP](INFO): Handled
2023-03-31T20:20:32.434Z,1680294032.434 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-03-31T20:20:32.434Z,1680294032.434 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T20:20:32.471Z,1680294032.471 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T20:20:32.865Z,1680294032.865 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T20:20:32.865Z,1680294032.865 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-03-31T20:20:34.973Z,1680294034.973 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:34.974Z,1680294034.974 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-03-31T20:20:34.974Z,1680294034.974 [DATMMP](INFO): Received message type: timesync
2023-03-31T20:20:34.974Z,1680294034.974 [DATMMP](INFO): Handled
2023-03-31T20:20:37.702Z,1680294037.702 [SBIT](FAULT): Mass: EXPECTED:-0.027810 ACTUAL:-0.027991
2023-03-31T20:20:37.702Z,1680294037.702 [SBIT](FAULT): Control surface position failure.
2023-03-31T20:20:38.105Z,1680294038.105 [SBIT](CRITICAL): SBIT FAILED
2023-03-31T20:20:38.105Z,1680294038.105 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-03-31T20:20:38.106Z,1680294038.106 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;

2023-03-31T20:20:38.106Z,1680294038.106 [SBIT](IMPORTANT): BPC1.loadAtStartup=1 bool;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): DATMMP.sbdAddress=9 enum;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): Depth_Keller.offset=3.9 decibar;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): Express none CBIT.ampHoursUsed;

2023-03-31T20:20:38.107Z,1680294038.107 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;

2023-03-31T20:20:38.108Z,1680294038.108 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-03-31T20:20:38.108Z,1680294038.108 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-03-31T20:20:38.108Z,1680294038.108 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-03-31T20:20:38.108Z,1680294038.108 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-03-31T20:20:38.108Z,1680294038.108 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=341.374110 cubic_centimeter;

2023-03-31T20:20:38.108Z,1680294038.108 [SBIT](IMPORTANT): VerticalControl.massDefault=-27.810335 millimeter;

2023-03-31T20:20:38.108Z,1680294038.108 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2023-03-31T20:20:38.109Z,1680294038.109 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter;

2023-03-31T20:20:38.109Z,1680294038.109 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter;

2023-03-31T20:20:38.109Z,1680294038.109 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=15 meter;

2023-03-31T20:20:38.109Z,1680294038.109 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=20 meter;

2023-03-31T20:20:38.110Z,1680294038.110 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-03-31T20:20:38.111Z,1680294038.111 [CBIT](INFO): Critical error at 20230331T202038
2023-03-31T20:20:38.503Z,1680294038.503 [MissionManager](IMPORTANT): Started mission Startup
2023-03-31T20:20:38.504Z,1680294038.504 [Startup] Running Loop=1
2023-03-31T20:20:38.504Z,1680294038.504 [Startup](DEBUG): Aggregate::initialize Startup
2023-03-31T20:20:38.504Z,1680294038.504 [Startup:A.GoToSurface] Running Loop=1
2023-03-31T20:20:38.504Z,1680294038.504 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-31T20:20:38.506Z,1680294038.506 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-31T20:20:38.506Z,1680294038.506 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-31T20:20:38.506Z,1680294038.506 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-31T20:20:38.507Z,1680294038.507 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-31T20:20:38.507Z,1680294038.507 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-31T20:20:38.508Z,1680294038.508 [Startup:StartupSatComms] Running Loop=1
2023-03-31T20:20:38.509Z,1680294038.509 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-03-31T20:20:38.509Z,1680294038.509 [Startup:StartupSatComms:A] Running Loop=1
2023-03-31T20:20:38.902Z,1680294038.902 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-03-31T20:20:43.809Z,1680294043.809 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:43.810Z,1680294043.810 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-03-31T20:20:43.810Z,1680294043.810 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:20:43.810Z,1680294043.810 [DATMMP](INFO): Handled
2023-03-31T20:20:44.061Z,1680294044.061 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:44.062Z,1680294044.062 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x14	 x2B	 x4A4
2023-03-31T20:20:44.063Z,1680294044.063 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294054.630784
2023-03-31T20:20:44.063Z,1680294044.063 [DATMMP](INFO): Rx ping set to:1680294044.062238
2023-03-31T20:20:44.063Z,1680294044.063 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:20:44.063Z,1680294044.063 [DATMMP](INFO): Handled
2023-03-31T20:20:44.565Z,1680294044.565 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:44.566Z,1680294044.566 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:20:44.566Z,1680294044.566 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:20:44.566Z,1680294044.566 [DATMMP](INFO): Handled
2023-03-31T20:20:44.817Z,1680294044.817 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:44.818Z,1680294044.818 [DATMMP](INFO): directional_debug rx: 	 x16F	 xFF02	 x38	 x3B	 x3F70	 x49B1	 x3D12	 x4F93
2023-03-31T20:20:44.819Z,1680294044.819 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:20:44.819Z,1680294044.819 [DATMMP](INFO): Handled
2023-03-31T20:20:45.069Z,1680294045.069 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:45.071Z,1680294045.071 [DATMMP](INFO): directional rx: 	 x158	 xFF08	 x46D	 xFE	 x846	 x22F	 x0	 xD59	 x2	 xFC7B	 x27B5966	 x-43569E0
2023-03-31T20:20:45.073Z,1680294045.073 [DATMMP](INFO): Received message type: directional
2023-03-31T20:20:45.073Z,1680294045.073 [DATMMP](INFO): Handled
2023-03-31T20:20:45.321Z,1680294045.321 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:45.322Z,1680294045.322 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 xB	 x0	 x0	 x9	 x0
2023-03-31T20:20:45.323Z,1680294045.323 [DATMMP](INFO): DATA Src=9, Dst=0
2023-03-31T20:20:45.323Z,1680294045.323 [DATMMP](INFO): Received message type: remote_header
2023-03-31T20:20:45.323Z,1680294045.323 [DATMMP](INFO): Handled
2023-03-31T20:20:45.573Z,1680294045.573 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:45.575Z,1680294045.575 [DATMMP](INFO): acstats rx: 	 x9	 x5	 xFFFF	 x183	 x24	 x52	 xAD	 xAF	 xD	 xFFFF	 x0	 x3D	 x0	 x0
2023-03-31T20:20:45.575Z,1680294045.575 [DATMMP](INFO): Received message type: acstats
2023-03-31T20:20:45.575Z,1680294045.575 [DATMMP](INFO): Handled
2023-03-31T20:20:45.825Z,1680294045.825 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:20:45.826Z,1680294045.826 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-03-31T20:20:45.826Z,1680294045.826 [DATMMP](INFO): Received message type: timesync
2023-03-31T20:20:45.826Z,1680294045.826 [DATMMP](INFO): Handled
2023-03-31T20:21:15.570Z,1680294075.570 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:15.570Z,1680294075.570 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-03-31T20:21:15.570Z,1680294075.570 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:21:15.571Z,1680294075.571 [DATMMP](INFO): Handled
2023-03-31T20:21:15.821Z,1680294075.821 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:15.822Z,1680294075.822 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x15	 xD	 x1FA9
2023-03-31T20:21:15.823Z,1680294075.823 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294088.157696
2023-03-31T20:21:15.823Z,1680294075.823 [DATMMP](INFO): Rx ping set to:1680294075.822212
2023-03-31T20:21:15.823Z,1680294075.823 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:21:15.823Z,1680294075.823 [DATMMP](INFO): Handled
2023-03-31T20:21:16.074Z,1680294076.074 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:16.076Z,1680294076.076 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:21:16.077Z,1680294076.077 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:21:16.077Z,1680294076.077 [DATMMP](INFO): Handled
2023-03-31T20:21:16.325Z,1680294076.325 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:16.326Z,1680294076.326 [DATMMP](INFO): directional_debug rx: 	 xE4	 xFE34	 x39	 x2B	 x3CF0	 x4971	 x3DD2	 x4CC3
2023-03-31T20:21:16.327Z,1680294076.327 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:21:16.327Z,1680294076.327 [DATMMP](INFO): Handled
2023-03-31T20:21:16.577Z,1680294076.577 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:16.579Z,1680294076.579 [DATMMP](INFO): directional rx: 	 xD0	 xFE30	 x4F8	 x1CC	 x8AE	 x14E	 x0	 xD62	 x3	 xFC7C	 x27B5966	 x-43569E0
2023-03-31T20:21:16.581Z,1680294076.581 [DATMMP](INFO): Received message type: directional
2023-03-31T20:21:16.581Z,1680294076.581 [DATMMP](INFO): Handled
2023-03-31T20:21:16.829Z,1680294076.829 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:16.830Z,1680294076.830 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 xB	 x0	 x0	 x9	 x0
2023-03-31T20:21:16.831Z,1680294076.831 [DATMMP](INFO): DATA Src=9, Dst=0
2023-03-31T20:21:16.831Z,1680294076.831 [DATMMP](INFO): Received message type: remote_header
2023-03-31T20:21:16.831Z,1680294076.831 [DATMMP](INFO): Handled
2023-03-31T20:21:17.082Z,1680294077.082 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:17.083Z,1680294077.083 [DATMMP](INFO): acstats rx: 	 x9	 x5	 xFFFF	 x16E	 x3B	 x4C	 xBA	 xB5	 xD	 xFFFF	 x0	 x3D	 x0	 x0
2023-03-31T20:21:17.084Z,1680294077.084 [DATMMP](INFO): Received message type: acstats
2023-03-31T20:21:17.084Z,1680294077.084 [DATMMP](INFO): Handled
2023-03-31T20:21:29.933Z,1680294089.933 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:29.934Z,1680294089.934 [DATMMP](INFO): doppler rx: 	 x0
2023-03-31T20:21:29.934Z,1680294089.934 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:21:29.934Z,1680294089.934 [DATMMP](INFO): Handled
2023-03-31T20:21:30.185Z,1680294090.185 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:30.186Z,1680294090.186 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x15	 x1C	 xA2D
2023-03-31T20:21:30.187Z,1680294090.187 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294113.460800
2023-03-31T20:21:30.187Z,1680294090.187 [DATMMP](INFO): Rx ping set to:1680294090.186415
2023-03-31T20:21:30.187Z,1680294090.187 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:21:30.187Z,1680294090.187 [DATMMP](INFO): Handled
2023-03-31T20:21:30.689Z,1680294090.689 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:30.690Z,1680294090.690 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:21:30.690Z,1680294090.690 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:21:30.690Z,1680294090.690 [DATMMP](INFO): Handled
2023-03-31T20:21:30.941Z,1680294090.941 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:30.942Z,1680294090.942 [DATMMP](INFO): directional_debug rx: 	 x1C3	 xFE21	 x39	 x31	 x3760	 x41B1	 x3832	 x4413
2023-03-31T20:21:30.943Z,1680294090.943 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:21:30.943Z,1680294090.943 [DATMMP](INFO): Handled
2023-03-31T20:21:31.193Z,1680294091.193 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:31.195Z,1680294091.195 [DATMMP](INFO): directional rx: 	 x1AF	 xFE18	 x419	 x1DF	 x959	 x193	 x0	 xD63	 x4	 xFC7C	 x27B5966	 x-43569E0
2023-03-31T20:21:31.197Z,1680294091.197 [DATMMP](INFO): Received message type: directional
2023-03-31T20:21:31.197Z,1680294091.197 [DATMMP](INFO): Handled
2023-03-31T20:21:31.445Z,1680294091.445 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:31.446Z,1680294091.446 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 xB	 x0	 x0	 x9	 x0
2023-03-31T20:21:31.447Z,1680294091.447 [DATMMP](INFO): DATA Src=9, Dst=0
2023-03-31T20:21:31.447Z,1680294091.447 [DATMMP](INFO): Received message type: remote_header
2023-03-31T20:21:31.447Z,1680294091.447 [DATMMP](INFO): Handled
2023-03-31T20:21:31.698Z,1680294091.698 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:31.706Z,1680294091.706 [DATMMP](INFO): acstats rx: 	 x9	 x5	 x0	 x176	 x21	 x48	 xB4	 xB5	 xD	 xFFFF	 x0	 x3F	 x0	 x0
2023-03-31T20:21:31.706Z,1680294091.706 [DATMMP](INFO): Received message type: acstats
2023-03-31T20:21:31.707Z,1680294091.707 [DATMMP](INFO): Handled
2023-03-31T20:21:38.692Z,1680294098.692 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-31T20:20:38.5Z
2023-03-31T20:21:38.692Z,1680294098.692 [Startup:StartupSatComms:A] Stopped
2023-03-31T20:21:38.692Z,1680294098.692 [Startup:StartupSatComms:B] Running Loop=1
2023-03-31T20:21:39.102Z,1680294099.102 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-31T20:21:45.315Z,1680294105.315 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:45.315Z,1680294105.315 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-03-31T20:21:45.316Z,1680294105.316 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:21:45.316Z,1680294105.316 [DATMMP](INFO): Handled
2023-03-31T20:21:45.565Z,1680294105.565 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:45.566Z,1680294105.566 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x15	 x2B	 x19CC
2023-03-31T20:21:45.567Z,1680294105.567 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294103.516096
2023-03-31T20:21:45.567Z,1680294105.567 [DATMMP](INFO): Rx ping set to:1680294105.566203
2023-03-31T20:21:45.567Z,1680294105.567 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:21:45.567Z,1680294105.567 [DATMMP](INFO): Handled
2023-03-31T20:21:46.069Z,1680294106.069 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:46.070Z,1680294106.070 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:21:46.070Z,1680294106.070 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:21:46.070Z,1680294106.070 [DATMMP](INFO): Handled
2023-03-31T20:21:46.321Z,1680294106.321 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:46.322Z,1680294106.322 [DATMMP](INFO): directional_debug rx: 	 x1A8	 xFE5B	 x39	 x3E	 x3750	 x4241	 x3772	 x4573
2023-03-31T20:21:46.323Z,1680294106.323 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:21:46.324Z,1680294106.324 [DATMMP](INFO): Handled
2023-03-31T20:21:46.573Z,1680294106.573 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:46.575Z,1680294106.575 [DATMMP](INFO): directional rx: 	 x199	 xFE53	 x434	 x1A5	 x922	 x1C1	 x0	 xD64	 x5	 xFC7C	 x27B5966	 x-43569E0
2023-03-31T20:21:46.577Z,1680294106.577 [DATMMP](INFO): Received message type: directional
2023-03-31T20:21:46.577Z,1680294106.577 [DATMMP](INFO): Handled
2023-03-31T20:21:46.825Z,1680294106.825 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:46.826Z,1680294106.826 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 xB	 x0	 x0	 x9	 x0
2023-03-31T20:21:46.827Z,1680294106.827 [DATMMP](INFO): DATA Src=9, Dst=0
2023-03-31T20:21:46.827Z,1680294106.827 [DATMMP](INFO): Received message type: remote_header
2023-03-31T20:21:46.827Z,1680294106.827 [DATMMP](INFO): Handled
2023-03-31T20:21:47.077Z,1680294107.077 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:21:47.079Z,1680294107.079 [DATMMP](INFO): acstats rx: 	 x9	 x5	 xFFFF	 x97	 x1F	 x50	 xA9	 xAE	 xD	 xFFFF	 x0	 x3F	 x0	 x0
2023-03-31T20:21:47.079Z,1680294107.079 [DATMMP](INFO): Received message type: acstats
2023-03-31T20:21:47.079Z,1680294107.079 [DATMMP](INFO): Handled
2023-03-31T20:21:51.252Z,1680294111.252 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T012134/Courier0331.lzma
2023-03-31T20:21:52.255Z,1680294112.255 [DataOverHttps](INFO): Moved sent file to Logs/20230331T012134/Courier0331.lzma.bak
2023-03-31T20:21:52.255Z,1680294112.255 [DataOverHttps](INFO): SBD MOMSN=17882692
2023-03-31T20:22:19.304Z,1680294139.304 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20230331T201721/Courier0000.lzma
2023-03-31T20:22:20.306Z,1680294140.306 [DataOverHttps](INFO): Moved sent file to Logs/20230331T201721/Courier0000.lzma.bak
2023-03-31T20:22:20.307Z,1680294140.307 [DataOverHttps](INFO): SBD MOMSN=17882694
2023-03-31T20:22:20.381Z,1680294140.381 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00009999.99,000.00999.99,+9999.999,+9999.99
2023-03-31T20:22:31.445Z,1680294151.445 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:31.446Z,1680294151.446 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-03-31T20:22:31.446Z,1680294151.446 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:22:31.446Z,1680294151.446 [DATMMP](INFO): Handled
2023-03-31T20:22:31.697Z,1680294151.697 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:31.698Z,1680294151.698 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x16	 x1D	 x2087
2023-03-31T20:22:31.699Z,1680294151.699 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294166.333824
2023-03-31T20:22:31.699Z,1680294151.699 [DATMMP](INFO): Rx ping set to:1680294151.698115
2023-03-31T20:22:31.699Z,1680294151.699 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:22:31.699Z,1680294151.699 [DATMMP](INFO): Handled
2023-03-31T20:22:32.201Z,1680294152.201 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:32.202Z,1680294152.202 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:22:32.202Z,1680294152.202 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:22:32.202Z,1680294152.202 [DATMMP](INFO): Handled
2023-03-31T20:22:32.453Z,1680294152.453 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:32.455Z,1680294152.455 [DATMMP](INFO): directional_debug rx: 	 x1C8	 xFE06	 x3A	 x2F	 x1F10	 x2471	 x3022	 x3173
2023-03-31T20:22:32.456Z,1680294152.456 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:22:32.456Z,1680294152.456 [DATMMP](INFO): Handled
2023-03-31T20:22:32.711Z,1680294152.711 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:32.713Z,1680294152.713 [DATMMP](INFO): directional rx: 	 x1B2	 xFDFC	 x414	 x1FA	 x96B	 x179	 x0	 xD65	 x4	 xFC7A	 x27B5966	 x-43569E0
2023-03-31T20:22:32.715Z,1680294152.715 [DATMMP](INFO): Received message type: directional
2023-03-31T20:22:32.715Z,1680294152.715 [DATMMP](INFO): Handled
2023-03-31T20:22:32.961Z,1680294152.961 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:32.963Z,1680294152.963 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 xB	 x0	 x0	 x9	 x0
2023-03-31T20:22:32.963Z,1680294152.963 [DATMMP](INFO): DATA Src=9, Dst=0
2023-03-31T20:22:32.963Z,1680294152.963 [DATMMP](INFO): Received message type: remote_header
2023-03-31T20:22:32.963Z,1680294152.963 [DATMMP](INFO): Handled
2023-03-31T20:22:33.213Z,1680294153.213 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:33.215Z,1680294153.215 [DATMMP](INFO): acstats rx: 	 x9	 x5	 xFFFF	 x177	 x3A	 x4B	 xB8	 xB4	 xD	 xFFFF	 x0	 x3F	 x0	 x0
2023-03-31T20:22:33.215Z,1680294153.215 [DATMMP](INFO): Received message type: acstats
2023-03-31T20:22:33.215Z,1680294153.215 [DATMMP](INFO): Handled
2023-03-31T20:22:38.966Z,1680294158.966 [Startup:StartupSatComms:B](INFO): Timed out from 2023-03-31T20:21:38.7Z
2023-03-31T20:22:38.967Z,1680294158.967 [Startup:StartupSatComms:B] Stopped
2023-03-31T20:22:38.967Z,1680294158.967 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-03-31T20:22:38.967Z,1680294158.967 [Startup:StartupSatComms] Stopped
2023-03-31T20:22:38.967Z,1680294158.967 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-03-31T20:22:38.968Z,1680294158.968 [Startup](INFO): Completed Startup
2023-03-31T20:22:38.968Z,1680294158.968 [MissionManager](INFO): Startup is completed.
2023-03-31T20:22:38.968Z,1680294158.968 [MissionManager](INFO): Uninitializing Mission Startup
2023-03-31T20:22:38.968Z,1680294158.968 [Startup] Stopped
2023-03-31T20:22:38.968Z,1680294158.968 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-03-31T20:22:38.968Z,1680294158.968 [Startup:A.GoToSurface] Stopped
2023-03-31T20:22:38.968Z,1680294158.968 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-31T20:22:39.375Z,1680294159.375 [MissionManager](IMPORTANT): Started mission Default
2023-03-31T20:22:39.376Z,1680294159.376 [Default] Running Loop=1
2023-03-31T20:22:39.376Z,1680294159.376 [Default](DEBUG): Aggregate::initialize Default
2023-03-31T20:22:39.376Z,1680294159.376 [Default:B.GoToSurface] Running Loop=1
2023-03-31T20:22:39.376Z,1680294159.376 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-31T20:22:39.376Z,1680294159.376 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-31T20:22:39.381Z,1680294159.381 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-31T20:22:39.382Z,1680294159.382 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-31T20:22:39.382Z,1680294159.382 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-31T20:22:39.382Z,1680294159.382 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-31T20:22:39.382Z,1680294159.382 [Default:A.Wait] Running Loop=1
2023-03-31T20:22:39.382Z,1680294159.382 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-31T20:22:41.717Z,1680294161.717 [DataOverHttps](INFO): Sending 350 bytes from file Logs/20230331T012134/Express0332.lzma
2023-03-31T20:22:42.719Z,1680294162.719 [DataOverHttps](INFO): Moved sent file to Logs/20230331T012134/Express0332.lzma.bak
2023-03-31T20:22:42.719Z,1680294162.719 [DataOverHttps](INFO): SBD MOMSN=17882699
2023-03-31T20:22:47.073Z,1680294167.073 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:47.074Z,1680294167.074 [DATMMP](INFO): doppler rx: 	 x0
2023-03-31T20:22:47.074Z,1680294167.074 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:22:47.074Z,1680294167.074 [DATMMP](INFO): Handled
2023-03-31T20:22:47.325Z,1680294167.325 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:47.326Z,1680294167.326 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x16	 x2D	 x916
2023-03-31T20:22:47.327Z,1680294167.327 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294187.749568
2023-03-31T20:22:47.327Z,1680294167.327 [DATMMP](INFO): Rx ping set to:1680294167.326112
2023-03-31T20:22:47.327Z,1680294167.327 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:22:47.327Z,1680294167.327 [DATMMP](INFO): Handled
2023-03-31T20:22:47.577Z,1680294167.577 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:47.578Z,1680294167.578 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:22:47.578Z,1680294167.578 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:22:47.578Z,1680294167.578 [DATMMP](INFO): Handled
2023-03-31T20:22:47.829Z,1680294167.829 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:47.831Z,1680294167.831 [DATMMP](INFO): directional_debug rx: 	 x15C	 xFEDD	 x37	 x3E	 x3E50	 x4941	 x3DF2	 x4D83
2023-03-31T20:22:47.831Z,1680294167.831 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:22:47.832Z,1680294167.832 [DATMMP](INFO): Handled
2023-03-31T20:22:48.081Z,1680294168.081 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:48.083Z,1680294168.083 [DATMMP](INFO): directional rx: 	 x148	 xFEE0	 x480	 x123	 x863	 x208	 x0	 xD60	 x4	 xFC7C	 x27B5966	 x-43569E0
2023-03-31T20:22:48.085Z,1680294168.085 [DATMMP](INFO): Received message type: directional
2023-03-31T20:22:48.085Z,1680294168.085 [DATMMP](INFO): Handled
2023-03-31T20:22:48.333Z,1680294168.333 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:48.334Z,1680294168.334 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 xB	 x0	 x0	 x9	 x0
2023-03-31T20:22:48.335Z,1680294168.335 [DATMMP](INFO): DATA Src=9, Dst=0
2023-03-31T20:22:48.335Z,1680294168.335 [DATMMP](INFO): Received message type: remote_header
2023-03-31T20:22:48.335Z,1680294168.335 [DATMMP](INFO): Handled
2023-03-31T20:22:48.585Z,1680294168.585 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:22:48.587Z,1680294168.587 [DATMMP](INFO): acstats rx: 	 x9	 x5	 x0	 x16F	 x3B	 x50	 xB6	 xB5	 xD	 xFFFF	 x0	 x40	 x0	 x0
2023-03-31T20:22:48.587Z,1680294168.587 [DATMMP](INFO): Received message type: acstats
2023-03-31T20:22:48.587Z,1680294168.587 [DATMMP](INFO): Handled
2023-03-31T20:22:52.759Z,1680294172.759 [Default:A.Wait](INFO): Done Waiting.
2023-03-31T20:22:52.760Z,1680294172.760 [Default:A.Wait] Stopped
2023-03-31T20:22:52.760Z,1680294172.760 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-31T20:22:53.165Z,1680294173.165 [Default:CheckIn] Running Loop=1
2023-03-31T20:22:53.166Z,1680294173.166 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-31T20:22:53.166Z,1680294173.166 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-31T20:22:53.557Z,1680294173.557 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-31T20:23:02.197Z,1680294182.197 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:02.198Z,1680294182.198 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-03-31T20:23:02.198Z,1680294182.198 [DATMMP](INFO): Received message type: doppler
2023-03-31T20:23:02.198Z,1680294182.198 [DATMMP](INFO): Handled
2023-03-31T20:23:02.449Z,1680294182.449 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:02.450Z,1680294182.450 [DATMMP](INFO): timestamp rx: 	 x1	 x14	 x17	 x0	 x16C1
2023-03-31T20:23:02.451Z,1680294182.451 [DATMMP](INFO): Rx dataTimestamp_ set to:1680294236.927744
2023-03-31T20:23:02.451Z,1680294182.451 [DATMMP](INFO): Rx ping set to:1680294182.450201
2023-03-31T20:23:02.451Z,1680294182.451 [DATMMP](INFO): Received message type: timestamp
2023-03-31T20:23:02.451Z,1680294182.451 [DATMMP](INFO): Handled
2023-03-31T20:23:02.953Z,1680294182.953 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:02.954Z,1680294182.954 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-03-31T20:23:02.954Z,1680294182.954 [DATMMP](INFO): Received message type: nav_stat
2023-03-31T20:23:02.954Z,1680294182.954 [DATMMP](INFO): Handled
2023-03-31T20:23:03.205Z,1680294183.205 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:03.207Z,1680294183.207 [DATMMP](INFO): directional_debug rx: 	 x140	 xFE63	 x38	 x28	 x3D10	 x4961	 x3DF2	 x4AA3
2023-03-31T20:23:03.208Z,1680294183.208 [DATMMP](INFO): Received message type: directional_debug
2023-03-31T20:23:03.208Z,1680294183.208 [DATMMP](INFO): Handled
2023-03-31T20:23:03.457Z,1680294183.457 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:03.459Z,1680294183.459 [DATMMP](INFO): directional rx: 	 x130	 xFE5E	 x49C	 x19D	 x8B1	 x19F	 x0	 xD52	 x4	 xFC7D	 x27B5966	 x-43569E0
2023-03-31T20:23:03.460Z,1680294183.460 [DATMMP](INFO): Received message type: directional
2023-03-31T20:23:03.461Z,1680294183.461 [DATMMP](INFO): Handled
2023-03-31T20:23:03.709Z,1680294183.709 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:03.710Z,1680294183.710 [DATMMP](INFO): header_err rx: 	 xFFFF	 x174	 x28	 x4A	 xAA	 xB0	 x5
2023-03-31T20:23:03.710Z,1680294183.710 [DATMMP](INFO): Received message type: header_err
2023-03-31T20:23:03.711Z,1680294183.711 [DATMMP](INFO): Handled
2023-03-31T20:23:14.907Z,1680294194.907 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-03-31T20:23:15.301Z,1680294195.301 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:15.303Z,1680294195.303 [DATMMP](ERROR): Failed to parse DAT message`:006E01040D00080001000000000000
2023-03-31T20:23:15.553Z,1680294195.553 [DATMMP](INFO): GSXN notify for xid: 0
2023-03-31T20:23:15.554Z,1680294195.554 [DATMMP](INFO): timesync rx: 	 x0	 x0	 x0	 x0
2023-03-31T20:23:15.554Z,1680294195.554 [DATMMP](INFO): Received message type: timesync
2023-03-31T20:23:15.554Z,1680294195.554 [DATMMP](INFO): Handled
2023-03-31T20:23:26.682Z,1680294206.682 [DataOverHttps](IMPORTANT): SBD MTMSN=20230331T202325
2023-03-31T20:23:33.499Z,1680294213.499 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-03-31T20:23:33.499Z,1680294213.499 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T20:23:33.510Z,1680294213.510 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T20:23:33.937Z,1680294213.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T20:23:33.937Z,1680294213.937 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-03-31T20:23:34.005Z,1680294214.005 [DataOverHttps](INFO): Received command: restart sys
2023-03-31T20:23:34.090Z,1680294214.090 [CommandExec](IMPORTANT): got command restart system
2023-03-31T20:23:36.225Z,1680294216.225 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T20:23:36.225Z,1680294216.225 [CommandExec](INFO): Uninitializing the command executive.
2023-03-31T20:23:36.225Z,1680294216.225 [CommandExec](INFO): Uninitializing the command scheduler.
2023-03-31T20:23:36.226Z,1680294216.226 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-31T20:23:36.253Z,1680294216.253 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-03-31T20:23:36.253Z,1680294216.253 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-03-31T20:23:36.253Z,1680294216.253 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-31T20:23:36.254Z,1680294216.254 [NavChartDb](INFO): Join timeout helper Thread ID is 1730
2023-03-31T20:23:36.545Z,1680294216.545 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T20:23:36.545Z,1680294216.545 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-31T20:23:36.553Z,1680294216.553 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-03-31T20:23:36.553Z,1680294216.553 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-31T20:23:36.553Z,1680294216.553 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1731
2023-03-31T20:23:36.853Z,1680294216.853 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T20:23:36.853Z,1680294216.853 [WetLabsBB2FL](INFO): Powering down
2023-03-31T20:23:36.854Z,1680294216.854 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-31T20:23:36.869Z,1680294216.869 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-03-31T20:23:36.869Z,1680294216.869 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-31T20:23:36.869Z,1680294216.869 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1732
2023-03-31T20:23:37.849Z,1680294217.849 [CTD_Seabird](INFO): Powering down
2023-03-31T20:23:37.861Z,1680294217.861 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T20:23:37.870Z,1680294217.870 [CTD_Seabird](INFO): Powering down
2023-03-31T20:23:37.885Z,1680294217.885 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
202