2023-03-20T22:18:20.183Z,1679350700.183 [Supervisor](DEBUG): Initializing supervisor.
2023-03-20T22:18:20.188Z,1679350700.188 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-03-20T22:18:20.188Z,1679350700.188 [SyncHandler](INFO): Protected caller Thread ID is 831
2023-03-20T22:18:20.189Z,1679350700.189 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-03-20T22:18:20.190Z,1679350700.190 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-03-20T22:18:20.190Z,1679350700.190 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832
2023-03-20T22:18:20.194Z,1679350700.194 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-03-20T22:18:20.211Z,1679350700.211 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-03-20T22:18:20.212Z,1679350700.212 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-03-20T22:18:20.213Z,1679350700.213 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833
2023-03-20T22:18:20.217Z,1679350700.217 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-03-20T22:18:20.218Z,1679350700.218 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-03-20T22:18:20.218Z,1679350700.218 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834
2023-03-20T22:18:20.220Z,1679350700.220 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-03-20T22:18:20.221Z,1679350700.221 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-03-20T22:18:20.221Z,1679350700.221 [logger ThreadHandler](INFO): Protected caller Thread ID is 835
2023-03-20T22:18:20.225Z,1679350700.225 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-03-20T22:18:20.225Z,1679350700.225 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-03-20T22:18:20.229Z,1679350700.229 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-03-20T22:18:20.553Z,1679350700.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-03-20T22:18:20.555Z,1679350700.555 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-03-20T22:18:21.213Z,1679350701.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-03-20T22:18:21.213Z,1679350701.213 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-03-20T22:18:21.320Z,1679350701.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-03-20T22:18:21.322Z,1679350701.322 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-03-20T22:18:21.797Z,1679350701.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-03-20T22:18:21.799Z,1679350701.799 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-03-20T22:18:21.894Z,1679350701.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-03-20T22:18:21.895Z,1679350701.895 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-03-20T22:18:21.977Z,1679350701.977 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-03-20T22:18:22.362Z,1679350702.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-03-20T22:18:22.363Z,1679350702.363 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-03-20T22:18:22.575Z,1679350702.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-03-20T22:18:22.575Z,1679350702.575 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-03-20T22:18:22.710Z,1679350702.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-03-20T22:18:22.711Z,1679350702.711 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-03-20T22:18:22.945Z,1679350702.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-03-20T22:18:22.946Z,1679350702.946 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-03-20T22:18:23.457Z,1679350703.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-03-20T22:18:23.459Z,1679350703.459 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-03-20T22:18:23.828Z,1679350703.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-03-20T22:18:24.174Z,1679350704.174 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-03-20T22:18:24.353Z,1679350704.353 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-03-20T22:18:24.532Z,1679350704.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-03-20T22:18:24.534Z,1679350704.534 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-03-20T22:18:24.746Z,1679350704.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-03-20T22:18:24.747Z,1679350704.747 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-03-20T22:18:25.030Z,1679350705.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-03-20T22:18:25.032Z,1679350705.032 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2023-03-20T22:18:25.036Z,1679350705.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2023-03-20T22:18:25.143Z,1679350705.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2023-03-20T22:18:25.284Z,1679350705.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2023-03-20T22:18:25.369Z,1679350705.369 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2023-03-20T22:18:25.451Z,1679350705.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2023-03-20T22:18:25.562Z,1679350705.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2023-03-20T22:18:25.729Z,1679350705.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2023-03-20T22:18:25.989Z,1679350705.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-03-20T22:18:25.989Z,1679350705.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2023-03-20T22:18:26.117Z,1679350706.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2023-03-20T22:18:26.237Z,1679350706.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2023-03-20T22:18:26.355Z,1679350706.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2023-03-20T22:18:26.455Z,1679350706.455 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-03-20T22:18:26.471Z,1679350706.471 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-03-20T22:18:26.599Z,1679350706.599 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-03-20T22:18:26.601Z,1679350706.601 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-03-20T22:18:26.617Z,1679350706.617 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-03-20T22:18:26.617Z,1679350706.617 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-03-20T22:18:26.690Z,1679350706.690 [DepthRateCalculator] Loaded
2023-03-20T22:18:26.690Z,1679350706.690 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-03-20T22:18:26.696Z,1679350706.696 [PitchRateCalculator] Loaded
2023-03-20T22:18:26.696Z,1679350706.696 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-03-20T22:18:26.721Z,1679350706.721 [SpeedCalculator] Loaded
2023-03-20T22:18:26.721Z,1679350706.721 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-03-20T22:18:26.726Z,1679350706.726 [YawRateCalculator] Loaded
2023-03-20T22:18:26.726Z,1679350706.726 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-03-20T22:18:26.748Z,1679350706.748 [ElevatorOffsetCalculator] Loaded
2023-03-20T22:18:26.749Z,1679350706.749 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-03-20T22:18:26.749Z,1679350706.749 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-03-20T22:18:26.751Z,1679350706.751 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-03-20T22:18:26.820Z,1679350706.820 [VerticalControl](DEBUG): Construct VerticalControl.
2023-03-20T22:18:26.876Z,1679350706.876 [VerticalControl] Loaded
2023-03-20T22:18:26.876Z,1679350706.876 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-03-20T22:18:26.879Z,1679350706.879 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-03-20T22:18:26.920Z,1679350706.920 [HorizontalControl] Loaded
2023-03-20T22:18:26.920Z,1679350706.920 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-03-20T22:18:26.923Z,1679350706.923 [SpeedControl](DEBUG): Construct SpeedControl.
2023-03-20T22:18:26.925Z,1679350706.925 [SpeedControl] Loaded
2023-03-20T22:18:26.926Z,1679350706.926 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-03-20T22:18:26.928Z,1679350706.928 [LoopControl](DEBUG): Construct LoopControl.
2023-03-20T22:18:26.929Z,1679350706.929 [LoopControl] Loaded
2023-03-20T22:18:26.929Z,1679350706.929 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-03-20T22:18:26.930Z,1679350706.930 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-03-20T22:18:26.930Z,1679350706.930 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-03-20T22:18:27.069Z,1679350707.069 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-03-20T22:18:27.070Z,1679350707.070 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-03-20T22:18:27.083Z,1679350707.083 [NavChart] Loaded
2023-03-20T22:18:27.084Z,1679350707.084 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-03-20T22:18:27.089Z,1679350707.089 [UniversalFixResidualReporter] Loaded
2023-03-20T22:18:27.090Z,1679350707.090 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-03-20T22:18:27.091Z,1679350707.091 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-03-20T22:18:27.093Z,1679350707.093 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-03-20T22:18:27.279Z,1679350707.279 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-03-20T22:18:27.280Z,1679350707.280 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-03-20T22:18:28.049Z,1679350708.049 [AHRS_M2] Loaded
2023-03-20T22:18:28.049Z,1679350708.049 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-03-20T22:18:28.300Z,1679350708.300 [BackseatComponent] Loaded
2023-03-20T22:18:28.300Z,1679350708.300 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-03-20T22:18:28.301Z,1679350708.301 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-03-20T22:18:28.302Z,1679350708.302 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 918
2023-03-20T22:18:28.304Z,1679350708.304 [LcmUniversalReporter] Loaded
2023-03-20T22:18:28.305Z,1679350708.305 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-03-20T22:18:28.447Z,1679350708.447 [DataOverHttps] Loaded
2023-03-20T22:18:28.447Z,1679350708.447 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-03-20T22:18:28.448Z,1679350708.448 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-03-20T22:18:28.448Z,1679350708.448 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 919
2023-03-20T22:18:28.469Z,1679350708.469 [Depth_Keller] Loaded
2023-03-20T22:18:28.469Z,1679350708.469 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-03-20T22:18:28.474Z,1679350708.474 [DropWeight] Loaded
2023-03-20T22:18:28.475Z,1679350708.475 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-03-20T22:18:28.512Z,1679350708.512 [DVL_micro] Loaded
2023-03-20T22:18:28.513Z,1679350708.513 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-03-20T22:18:28.575Z,1679350708.575 [NAL9602] Loaded
2023-03-20T22:18:28.575Z,1679350708.575 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-03-20T22:18:28.605Z,1679350708.605 [Onboard] Loaded
2023-03-20T22:18:28.606Z,1679350708.606 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-03-20T22:18:28.607Z,1679350708.607 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-03-20T22:18:28.607Z,1679350708.607 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 920
2023-03-20T22:18:28.619Z,1679350708.619 [Power24vConverter] Loaded
2023-03-20T22:18:28.619Z,1679350708.619 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-03-20T22:18:28.633Z,1679350708.633 [Radio_Surface] Loaded
2023-03-20T22:18:28.633Z,1679350708.633 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-03-20T22:18:28.634Z,1679350708.634 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4098D4E0
2023-03-20T22:18:28.634Z,1679350708.634 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 921
2023-03-20T22:18:28.727Z,1679350708.727 [DAT] Loaded
2023-03-20T22:18:28.727Z,1679350708.727 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-03-20T22:18:28.728Z,1679350708.728 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409BD4E0
2023-03-20T22:18:28.728Z,1679350708.728 [DAT ThreadHandler](INFO): Protected caller Thread ID is 922
2023-03-20T22:18:28.729Z,1679350708.729 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-03-20T22:18:28.730Z,1679350708.730 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-03-20T22:18:28.893Z,1679350708.893 [BuoyancyServo] Loaded
2023-03-20T22:18:28.893Z,1679350708.893 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-03-20T22:18:28.914Z,1679350708.914 [ElevatorServo] Loaded
2023-03-20T22:18:28.914Z,1679350708.914 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-03-20T22:18:28.933Z,1679350708.933 [MassServo] Loaded
2023-03-20T22:18:28.934Z,1679350708.934 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-03-20T22:18:28.952Z,1679350708.952 [RudderServo] Loaded
2023-03-20T22:18:28.952Z,1679350708.952 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-03-20T22:18:28.967Z,1679350708.967 [ThrusterHE] Loaded
2023-03-20T22:18:28.967Z,1679350708.967 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-03-20T22:18:28.968Z,1679350708.968 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-03-20T22:18:28.968Z,1679350708.968 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-03-20T22:18:29.053Z,1679350709.053 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-03-20T22:18:29.053Z,1679350709.053 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-03-20T22:18:29.097Z,1679350709.097 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-03-20T22:18:29.097Z,1679350709.097 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-03-20T22:18:29.663Z,1679350709.663 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-03-20T22:18:29.664Z,1679350709.664 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-03-20T22:18:29.990Z,1679350709.990 [CTD_Seabird] Loaded
2023-03-20T22:18:29.990Z,1679350709.990 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-03-20T22:18:29.991Z,1679350709.991 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0
2023-03-20T22:18:29.992Z,1679350709.992 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 923
2023-03-20T22:18:30.025Z,1679350710.025 [ESPComponent] Loaded
2023-03-20T22:18:30.025Z,1679350710.025 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-03-20T22:18:30.045Z,1679350710.045 [PAR_Licor] Loaded
2023-03-20T22:18:30.045Z,1679350710.045 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-03-20T22:18:30.077Z,1679350710.077 [WetLabsBB2FL] Loaded
2023-03-20T22:18:30.077Z,1679350710.077 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-03-20T22:18:30.078Z,1679350710.078 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B774E0
2023-03-20T22:18:30.078Z,1679350710.078 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 924
2023-03-20T22:18:30.079Z,1679350710.079 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-03-20T22:18:30.081Z,1679350710.081 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-03-20T22:18:30.254Z,1679350710.254 [SBIT](DEBUG): Construct Startup Built In Test.
2023-03-20T22:18:30.263Z,1679350710.263 [SBIT] Loaded
2023-03-20T22:18:30.264Z,1679350710.264 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-03-20T22:18:30.267Z,1679350710.267 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-03-20T22:18:30.280Z,1679350710.280 [IBIT] Loaded
2023-03-20T22:18:30.280Z,1679350710.280 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-03-20T22:18:30.286Z,1679350710.286 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-03-20T22:18:30.519Z,1679350710.519 [CBIT] Loaded
2023-03-20T22:18:30.520Z,1679350710.520 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-03-20T22:18:30.520Z,1679350710.520 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-03-20T22:18:30.526Z,1679350710.526 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-03-20T22:18:30.529Z,1679350710.529 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-03-20T22:18:30.540Z,1679350710.540 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-03-20T22:18:30.541Z,1679350710.541 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BE54E0
2023-03-20T22:18:30.541Z,1679350710.541 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 925
2023-03-20T22:18:30.546Z,1679350710.546 [Supervisor](INFO): Main Thread ID is 827
2023-03-20T22:18:30.546Z,1679350710.546 [Supervisor](DEBUG): Running supervisor.
2023-03-20T22:18:30.547Z,1679350710.547 [CommandExec ThreadHandler](INFO): Handler Thread ID is 926
2023-03-20T22:18:30.547Z,1679350710.547 [CommandExec](INFO): Initializing the command executive.
2023-03-20T22:18:30.549Z,1679350710.549 [CommandLine ThreadHandler](INFO): Handler Thread ID is 927
2023-03-20T22:18:30.551Z,1679350710.551 [controlThread ThreadHandler](INFO): Handler Thread ID is 928
2023-03-20T22:18:30.551Z,1679350710.551 [controlThread](DEBUG): Initializing ControlThread
2023-03-20T22:18:30.552Z,1679350710.552 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-03-20T22:18:30.552Z,1679350710.552 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-03-20T22:18:30.553Z,1679350710.553 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-03-20T22:18:30.553Z,1679350710.553 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-03-20T22:18:30.553Z,1679350710.553 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-03-20T22:18:30.554Z,1679350710.554 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-03-20T22:18:30.556Z,1679350710.556 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-03-20T22:18:30.556Z,1679350710.556 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-03-20T22:18:30.557Z,1679350710.557 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-03-20T22:18:30.558Z,1679350710.558 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-03-20T22:18:30.558Z,1679350710.558 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-03-20T22:18:30.570Z,1679350710.570 [SBIT](INFO): Initialize SBIT Component.
2023-03-20T22:18:30.571Z,1679350710.571 [SBIT](IMPORTANT): git: 2023-02-15A-18-g952c73059
2023-03-20T22:18:30.571Z,1679350710.571 [SBIT](INFO): git hash: 952c7305905b924e3dfe6a20bfba2406582094af
2023-03-20T22:18:30.571Z,1679350710.571 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-03-20T22:18:30.572Z,1679350710.572 [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-20T22:18:30.573Z,1679350710.573 [SBIT](INFO): Beginning SBIT in 22.000000 seconds.
2023-03-20T22:18:30.574Z,1679350710.574 [IBIT](INFO): Initialize IBIT Component.
2023-03-20T22:18:30.575Z,1679350710.575 [CBIT](DEBUG): Initialize CBIT Component.
2023-03-20T22:18:30.576Z,1679350710.576 [logger ThreadHandler](INFO): Handler Thread ID is 929
2023-03-20T22:18:30.586Z,1679350710.586 [CBIT](DEBUG): Initialized mux pins.
2023-03-20T22:18:30.586Z,1679350710.586 [CBIT](DEBUG): Initializing the watchdog timer.
2023-03-20T22:18:30.594Z,1679350710.594 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 930
2023-03-20T22:18:30.606Z,1679350710.606 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 931
2023-03-20T22:18:30.607Z,1679350710.607 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-03-20T22:18:30.610Z,1679350710.610 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-03-20T22:18:30.611Z,1679350710.611 [CBIT](DEBUG): Initializing heartbeat.
2023-03-20T22:18:30.618Z,1679350710.618 [Onboard ThreadHandler](INFO): Handler Thread ID is 932
2023-03-20T22:18:30.636Z,1679350710.636 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 933
2023-03-20T22:18:30.658Z,1679350710.658 [DAT ThreadHandler](INFO): Handler Thread ID is 934
2023-03-20T22:18:30.659Z,1679350710.659 [DAT](INFO): Powering up
2023-03-20T22:18:30.659Z,1679350710.659 [DAT](DEBUG): Initializing DAT.
2023-03-20T22:18:30.663Z,1679350710.663 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2023-03-20T22:18:30.664Z,1679350710.664 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-03-20T22:18:30.667Z,1679350710.667 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2023-03-20T22:18:30.670Z,1679350710.670 [WetLabsBB2FL](INFO): Powering up
2023-03-20T22:18:30.672Z,1679350710.672 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2023-03-20T22:18:30.680Z,1679350710.680 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-03-20T22:18:30.680Z,1679350710.680 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-03-20T22:18:30.680Z,1679350710.680 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-03-20T22:18:30.681Z,1679350710.681 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-03-20T22:18:30.681Z,1679350710.681 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-03-20T22:18:30.681Z,1679350710.681 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-03-20T22:18:30.681Z,1679350710.681 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-03-20T22:18:30.681Z,1679350710.681 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-03-20T22:18:30.682Z,1679350710.682 [CBIT](DEBUG): Deactivating GF circuits.
2023-03-20T22:18:30.682Z,1679350710.682 [CBIT](DEBUG): Deactivating emergency mode.
2023-03-20T22:18:30.718Z,1679350710.718 [CBIT](DEBUG): Backplane powered.
2023-03-20T22:18:30.719Z,1679350710.719 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-03-20T22:18:30.745Z,1679350710.745 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-20T22:18:30.780Z,1679350710.780 [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-20T22:18:30.781Z,1679350710.781 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-03-20T22:18:30.874Z,1679350710.874 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-03-20T22:18:30.875Z,1679350710.875 [Default:A.Wait](DEBUG): Construct Wait.
2023-03-20T22:18:30.878Z,1679350710.878 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-20T22:18:30.927Z,1679350710.927 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-20T22:18:30.930Z,1679350710.930 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-20T22:18:30.942Z,1679350710.942 [Default:E.Execute](DEBUG): Construct Execute.
2023-03-20T22:18:30.945Z,1679350710.945 [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-20T22:18:30.954Z,1679350710.954 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-03-20T22:18:30.961Z,1679350710.961 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-03-20T22:18:31.042Z,1679350711.042 [Radio_Surface](INFO): Powering up
2023-03-20T22:18:31.127Z,1679350711.127 [Power24vConverter](INFO): Powering up.
2023-03-20T22:18:31.173Z,1679350711.173 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-03-20T22:18:31.178Z,1679350711.178 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-03-20T22:18:31.179Z,1679350711.179 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-03-20T22:18:31.186Z,1679350711.186 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-03-20T22:18:31.187Z,1679350711.187 [MassServo](DEBUG): Initializing EZServoServo.
2023-03-20T22:18:31.194Z,1679350711.194 [MassServo](DEBUG): Initializing MassServo.
2023-03-20T22:18:31.195Z,1679350711.195 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-20T22:18:31.202Z,1679350711.202 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-20T22:18:31.203Z,1679350711.203 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-03-20T22:18:31.210Z,1679350711.210 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-03-20T22:18:31.403Z,1679350711.403 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-20T22:18:31.403Z,1679350711.403 [DropWeight] Hardware Fault, FailCount= 1
2023-03-20T22:18:31.403Z,1679350711.403 [DropWeight](ERROR): Hardware Fault
2023-03-20T22:18:31.429Z,1679350711.429 [CommandExec](FAULT): Scheduling is paused
2023-03-20T22:18:31.429Z,1679350711.429 [CBIT](INFO): Critical error at 20230320T221831
2023-03-20T22:18:31.429Z,1679350711.429 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-03-20T22:18:31.440Z,1679350711.440 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-20T22:18:31.441Z,1679350711.441 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-20T22:18:32.102Z,1679350712.102 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-03-20T22:18:32.102Z,1679350712.102 [RudderServo](FAULT): Rudder failed to initialize
2023-03-20T22:18:32.102Z,1679350712.102 [RudderServo] Communications Fault, FailCount= 1
2023-03-20T22:18:32.102Z,1679350712.102 [RudderServo](ERROR): Communications Fault
2023-03-20T22:18:32.104Z,1679350712.104 [CBIT](INFO): Critical error at 20230320T221831
2023-03-20T22:18:32.107Z,1679350712.107 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-03-20T22:18:32.337Z,1679350712.337 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-20T22:18:32.337Z,1679350712.337 [RudderServo](INFO): Powering down
2023-03-20T22:18:33.002Z,1679350713.002 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-20T22:18:33.123Z,1679350713.123 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-20T22:18:33.127Z,1679350713.127 [CBIT](INFO): Clearing failed state for component RudderServo
2023-03-20T22:18:33.127Z,1679350713.127 [RudderServo] No Fault, FailCount= 1
2023-03-20T22:18:33.282Z,1679350713.282 [WetLabsBB2FL](INFO): Powering down
2023-03-20T22:18:36.739Z,1679350716.739 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-03-20T22:18:40.719Z,1679350720.719 [CBIT](CRITICAL): Environmental Failure. Press:14.758260 PSI. Humidity:34%. Temp:25 C. ABORTING MISSION
2023-03-20T22:18:41.145Z,1679350721.145 [CBIT](INFO): Critical error at 20230320T221840
2023-03-20T22:18:42.503Z,1679350722.503 [DAT](INFO): DAT read: 

2023-03-20T22:18:42.504Z,1679350722.504 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-03-20T22:18:44.015Z,1679350724.015 [DAT](INFO): DAT read: MF Frequency Band

2023-03-20T22:18:44.016Z,1679350724.016 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-03-20T22:18:44.017Z,1679350724.017 [DAT](INFO): DAT read: Mar 20 2023 22:18:38 

2023-03-20T22:18:45.275Z,1679350725.275 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-03-20T22:18:45.277Z,1679350725.277 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-03-20T22:18:45.277Z,1679350725.277 [DAT](INFO): commRate: 800
2023-03-20T22:18:47.358Z,1679350727.358 [DAT](INFO): entering command mode
2023-03-20T22:18:47.358Z,1679350727.358 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:47.546Z,1679350727.546 [DAT](INFO): DAT read: 

2023-03-20T22:18:47.547Z,1679350727.547 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:47.547Z,1679350727.547 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:47.799Z,1679350727.799 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:47.799Z,1679350727.799 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.050Z,1679350728.050 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.051Z,1679350728.051 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.302Z,1679350728.302 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.303Z,1679350728.303 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.554Z,1679350728.554 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.555Z,1679350728.555 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.806Z,1679350728.806 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:48.806Z,1679350728.806 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.058Z,1679350729.058 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.058Z,1679350729.058 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.310Z,1679350729.310 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.311Z,1679350729.311 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.562Z,1679350729.562 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.563Z,1679350729.563 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.814Z,1679350729.814 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:49.815Z,1679350729.815 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.066Z,1679350730.066 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.067Z,1679350730.067 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.318Z,1679350730.318 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.318Z,1679350730.318 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.570Z,1679350730.570 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.571Z,1679350730.571 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.822Z,1679350730.822 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:50.823Z,1679350730.823 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.077Z,1679350731.077 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.077Z,1679350731.077 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.326Z,1679350731.326 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.327Z,1679350731.327 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.579Z,1679350731.579 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.579Z,1679350731.579 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.830Z,1679350731.830 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:51.830Z,1679350731.830 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.082Z,1679350732.082 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.083Z,1679350732.083 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.334Z,1679350732.334 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.335Z,1679350732.335 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.586Z,1679350732.586 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.587Z,1679350732.587 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.838Z,1679350732.838 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:52.839Z,1679350732.839 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.090Z,1679350733.090 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.090Z,1679350733.090 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.209Z,1679350733.209 [SBIT](IMPORTANT): Beginning Startup BIT
2023-03-20T22:18:53.233Z,1679350733.233 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-20T22:18:53.342Z,1679350733.342 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.343Z,1679350733.343 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.594Z,1679350733.594 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.595Z,1679350733.595 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.846Z,1679350733.846 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:53.847Z,1679350733.847 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.099Z,1679350734.099 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.099Z,1679350734.099 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.350Z,1679350734.350 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.350Z,1679350734.350 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.602Z,1679350734.602 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.603Z,1679350734.603 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.854Z,1679350734.854 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:54.854Z,1679350734.854 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.106Z,1679350735.106 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.107Z,1679350735.107 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.359Z,1679350735.359 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.359Z,1679350735.359 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.610Z,1679350735.610 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.610Z,1679350735.610 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.862Z,1679350735.862 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:55.863Z,1679350735.863 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.115Z,1679350736.115 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.116Z,1679350736.116 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.366Z,1679350736.366 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.367Z,1679350736.367 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.618Z,1679350736.618 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.619Z,1679350736.619 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.870Z,1679350736.870 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:56.871Z,1679350736.871 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.122Z,1679350737.122 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.123Z,1679350737.123 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.374Z,1679350737.374 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.375Z,1679350737.375 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.626Z,1679350737.626 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.627Z,1679350737.627 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.878Z,1679350737.878 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:57.879Z,1679350737.879 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.130Z,1679350738.130 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.131Z,1679350738.131 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.383Z,1679350738.383 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.383Z,1679350738.383 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.635Z,1679350738.635 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.635Z,1679350738.635 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.853Z,1679350738.853 [NAL9602](INFO): Powering up NAL9602
2023-03-20T22:18:58.907Z,1679350738.907 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:58.907Z,1679350738.907 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.158Z,1679350739.158 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.158Z,1679350739.158 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.411Z,1679350739.411 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.411Z,1679350739.411 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.662Z,1679350739.662 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.662Z,1679350739.662 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.914Z,1679350739.914 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:18:59.915Z,1679350739.915 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.166Z,1679350740.166 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.167Z,1679350740.167 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.418Z,1679350740.418 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.418Z,1679350740.418 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.670Z,1679350740.670 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.671Z,1679350740.671 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.923Z,1679350740.923 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:00.923Z,1679350740.923 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.174Z,1679350741.174 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.175Z,1679350741.175 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.426Z,1679350741.426 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.427Z,1679350741.427 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.678Z,1679350741.678 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.679Z,1679350741.679 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.931Z,1679350741.931 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:01.931Z,1679350741.931 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:02.182Z,1679350742.182 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:02.183Z,1679350742.182 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:02.434Z,1679350742.434 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:02.435Z,1679350742.435 [DAT](FAULT): failed to enter command mode
2023-03-20T22:19:02.750Z,1679350742.750 [DAT](INFO): entering command mode
2023-03-20T22:19:02.750Z,1679350742.750 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T22:19:02.927Z,1679350742.927 [CommandExec](IMPORTANT): got command strobe off
2023-03-20T22:19:02.927Z,1679350742.927 [CommandExec](IMPORTANT): Deactivating strobe
2023-03-20T22:19:02.939Z,1679350742.939 [DAT](INFO): DAT read: 

2023-03-20T22:19:02.939Z,1679350742.939 [DAT](INFO): DAT read: Command '+++' not found

2023-03-20T22:19:02.940Z,1679350742.940 [DAT](INFO): DAT read: Error

2023-03-20T22:19:02.940Z,1679350742.940 [DAT](INFO): setting verbose to 3
2023-03-20T22:19:03.191Z,1679350743.191 [DAT](INFO): DAT read: user:2>

2023-03-20T22:19:03.192Z,1679350743.192 [DAT](INFO): DAT read: Verbose         | 3           

2023-03-20T22:19:03.192Z,1679350743.192 [DAT](INFO): set verbose to 3
2023-03-20T22:19:03.192Z,1679350743.192 [DAT](INFO): setting DatVerbose to 27440
2023-03-20T22:19:03.443Z,1679350743.443 [DAT](INFO): DAT read: user:3>

2023-03-20T22:19:03.444Z,1679350743.444 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-03-20T22:19:03.444Z,1679350743.444 [DAT](INFO): set DatVerbose to 27440
2023-03-20T22:19:03.444Z,1679350743.444 [DAT](INFO): setting transmit power to 8
2023-03-20T22:19:03.694Z,1679350743.694 [DAT](INFO): DAT read: user:4>

2023-03-20T22:19:03.696Z,1679350743.696 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-03-20T22:19:03.697Z,1679350743.697 [DAT](INFO): set transmit power to 8
2023-03-20T22:19:03.699Z,1679350743.699 [DAT](INFO): setting local address to 8
2023-03-20T22:19:03.947Z,1679350743.947 [DAT](INFO): DAT read: user:5>

2023-03-20T22:19:03.948Z,1679350743.948 [DAT](INFO): DAT read: LocalAddr       | 8           

2023-03-20T22:19:03.948Z,1679350743.948 [DAT](INFO): set local address to 8
2023-03-20T22:19:03.949Z,1679350743.949 [DAT](INFO): Setting time to: 22:19:3 And date to:3/20/2023
2023-03-20T22:19:04.199Z,1679350744.199 [DAT](INFO): DAT read: user:6>

2023-03-20T22:19:04.200Z,1679350744.200 [DAT](INFO): DAT read: Mon Mar 20, 2023  22:19:03  

2023-03-20T22:19:04.200Z,1679350744.200 [DAT](INFO): Local DAT time set to Mon Mar 20, 2023  22:19:03  

2023-03-20T22:19:04.269Z,1679350744.269 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.020450
CHAN A1 (24V): -0.002583
CHAN A2 (12V): -0.003058
CHAN A3 (5V): 0.000651
CHAN B0 (3.3V): 0.000396
CHAN B1 (3.15aV): 0.000453
CHAN B2 (3.15bV): 0.000553
CHAN B3 (GND): 0.000902
OPEN: -0.004091
Full Scale: +/- 1 mA
2023-03-20T22:19:09.756Z,1679350749.756 [NAL9602](INFO): NAL9602 initialized
2023-03-20T22:19:10.103Z,1679350750.103 [CommandExec](IMPORTANT): got command strobe off
2023-03-20T22:19:10.103Z,1679350750.103 [CommandExec](IMPORTANT): Deactivating strobe
2023-03-20T22:19:47.367Z,1679350787.367 [SBIT](IMPORTANT): SBIT PASSED
2023-03-20T22:19:47.368Z,1679350787.368 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-03-20T22:19:47.368Z,1679350787.368 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=35 count;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): Depth_Keller.offset=3.9 decibar;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): Express mostRecent CBIT.ampHoursUsed;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=547.568399 cubic_centimeter;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): VerticalControl.massDefault=0.503160 millimeter;

2023-03-20T22:19:47.369Z,1679350787.369 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2023-03-20T22:19:47.370Z,1679350787.370 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter;

2023-03-20T22:19:47.370Z,1679350787.370 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter;

2023-03-20T22:19:47.747Z,1679350787.747 [MissionManager](IMPORTANT): Started mission Startup
2023-03-20T22:19:47.747Z,1679350787.747 [Startup] Running Loop=1
2023-03-20T22:19:47.747Z,1679350787.747 [Startup](DEBUG): Aggregate::initialize Startup
2023-03-20T22:19:47.747Z,1679350787.747 [Startup:A.GoToSurface] Running Loop=1
2023-03-20T22:19:47.748Z,1679350787.748 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-20T22:19:47.748Z,1679350787.748 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-20T22:19:47.748Z,1679350787.748 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-20T22:19:47.749Z,1679350787.749 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-20T22:19:47.749Z,1679350787.749 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-20T22:19:47.749Z,1679350787.749 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-20T22:19:47.751Z,1679350787.751 [Startup:StartupSatComms] Running Loop=1
2023-03-20T22:19:47.751Z,1679350787.751 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-03-20T22:19:47.751Z,1679350787.751 [Startup:StartupSatComms:A] Running Loop=1
2023-03-20T22:19:48.144Z,1679350788.144 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-03-20T22:20:47.960Z,1679350847.960 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-20T22:19:47.8Z
2023-03-20T22:20:47.960Z,1679350847.960 [Startup:StartupSatComms:A] Stopped
2023-03-20T22:20:47.960Z,1679350847.960 [Startup:StartupSatComms:B] Running Loop=1
2023-03-20T22:20:48.381Z,1679350848.381 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-20T22:20:50.509Z,1679350850.509 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004882
2023-03-20T22:20:53.676Z,1679350853.676 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20230320T214705/Courier0010.lzma
2023-03-20T22:20:54.574Z,1679350854.574 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-20T22:20:54.575Z,1679350854.575 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.1,000.0,000
2023-03-20T22:20:54.677Z,1679350854.677 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Courier0010.lzma.bak
2023-03-20T22:20:54.677Z,1679350854.677 [DataOverHttps](INFO): SBD MOMSN=17534321
2023-03-20T22:21:11.675Z,1679350871.675 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20230320T221820/Courier0000.lzma
2023-03-20T22:21:12.677Z,1679350872.677 [DataOverHttps](INFO): Moved sent file to Logs/20230320T221820/Courier0000.lzma.bak
2023-03-20T22:21:12.677Z,1679350872.677 [DataOverHttps](INFO): SBD MOMSN=17534327
2023-03-20T22:21:30.951Z,1679350890.951 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-03-20T22:21:30.951Z,1679350890.951 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:21:30.961Z,1679350890.961 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:21:31.033Z,1679350891.033 [DataOverHttps](INFO): Sending 609 bytes from file Logs/20230320T214705/Express0011.lzma
2023-03-20T22:21:31.354Z,1679350891.354 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:21:31.354Z,1679350891.354 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-03-20T22:21:32.033Z,1679350892.033 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Express0011.lzma.bak
2023-03-20T22:21:32.033Z,1679350892.033 [DataOverHttps](INFO): SBD MOMSN=17534336
2023-03-20T22:21:35.996Z,1679350895.996 [CommandExec](IMPORTANT): got command show variable sig
2023-03-20T22:21:36.002Z,1679350896.002 [CommandExec](IMPORTANT): sea_water_sigma_t (unknown)
2023-03-20T22:21:36.003Z,1679350896.003 [CommandExec](IMPORTANT): sea_water_sigma_theta (unknown)
2023-03-20T22:21:36.007Z,1679350896.007 [CommandExec](IMPORTANT): AHRS_M2.boresightMatrix (none)
2023-03-20T22:21:36.024Z,1679350896.024 [CommandExec](IMPORTANT): Simulator.designOmega (revolution_per_minute)
2023-03-20T22:21:36.025Z,1679350896.025 [CommandExec](IMPORTANT): Simulator.designPropEff (none)
2023-03-20T22:21:36.025Z,1679350896.025 [CommandExec](IMPORTANT): Simulator.designSpeed (meter_per_second)
2023-03-20T22:21:36.025Z,1679350896.025 [CommandExec](IMPORTANT): Simulator.designThrust (newton)
2023-03-20T22:21:36.026Z,1679350896.026 [CommandExec](IMPORTANT): Simulator.designTorque (newton_meter)
2023-03-20T22:21:36.116Z,1679350896.116 [CommandExec](IMPORTANT): NAL9602.sigQuality (count)
2023-03-20T22:21:48.339Z,1679350908.339 [Startup:StartupSatComms:B](INFO): Timed out from 2023-03-20T22:20:47.0Z
2023-03-20T22:21:48.339Z,1679350908.339 [Startup:StartupSatComms:B] Stopped
2023-03-20T22:21:48.340Z,1679350908.340 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-03-20T22:21:48.340Z,1679350908.340 [Startup:StartupSatComms] Stopped
2023-03-20T22:21:48.340Z,1679350908.340 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-03-20T22:21:48.340Z,1679350908.340 [Startup](INFO): Completed Startup
2023-03-20T22:21:48.341Z,1679350908.341 [MissionManager](INFO): Startup is completed.
2023-03-20T22:21:48.341Z,1679350908.341 [MissionManager](INFO): Uninitializing Mission Startup
2023-03-20T22:21:48.341Z,1679350908.341 [Startup] Stopped
2023-03-20T22:21:48.341Z,1679350908.341 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-03-20T22:21:48.341Z,1679350908.341 [Startup:A.GoToSurface] Stopped
2023-03-20T22:21:48.341Z,1679350908.341 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-20T22:21:48.728Z,1679350908.728 [MissionManager](IMPORTANT): Started mission Default
2023-03-20T22:21:48.728Z,1679350908.728 [Default] Running Loop=1
2023-03-20T22:21:48.728Z,1679350908.728 [Default](DEBUG): Aggregate::initialize Default
2023-03-20T22:21:48.728Z,1679350908.728 [Default:B.GoToSurface] Running Loop=1
2023-03-20T22:21:48.728Z,1679350908.728 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-20T22:21:48.729Z,1679350908.729 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-20T22:21:48.729Z,1679350908.729 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-20T22:21:48.729Z,1679350908.729 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-20T22:21:48.729Z,1679350908.729 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-20T22:21:48.730Z,1679350908.730 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-20T22:21:48.730Z,1679350908.730 [Default:A.Wait] Running Loop=1
2023-03-20T22:21:48.730Z,1679350908.730 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-20T22:21:53.312Z,1679350913.312 [DataOverHttps](INFO): Sending 1079 bytes from file Logs/20230320T221820/Express0001.lzma
2023-03-20T22:21:53.536Z,1679350913.536 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality
2023-03-20T22:21:54.313Z,1679350914.313 [DataOverHttps](INFO): Moved sent file to Logs/20230320T221820/Express0001.lzma.bak
2023-03-20T22:21:54.313Z,1679350914.313 [DataOverHttps](INFO): SBD MOMSN=17534355
2023-03-20T22:21:54.407Z,1679350914.407 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:21:55.228Z,1679350915.228 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:21:57.948Z,1679350917.948 [DAT](INFO): DAT read: user:7>Lowpower

2023-03-20T22:21:57.948Z,1679350917.948 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:7>Lowpower

2023-03-20T22:21:59.662Z,1679350919.662 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:00.497Z,1679350920.497 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:02.049Z,1679350922.049 [Default:A.Wait](INFO): Done Waiting.
2023-03-20T22:22:02.049Z,1679350922.049 [Default:A.Wait] Stopped
2023-03-20T22:22:02.050Z,1679350922.050 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-20T22:22:02.463Z,1679350922.463 [Default:CheckIn] Running Loop=1
2023-03-20T22:22:02.463Z,1679350922.463 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-20T22:22:02.464Z,1679350922.464 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-20T22:22:02.857Z,1679350922.857 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-20T22:22:04.918Z,1679350924.918 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:05.722Z,1679350925.722 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:10.172Z,1679350930.172 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:10.976Z,1679350930.976 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:15.414Z,1679350935.414 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:16.241Z,1679350936.241 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:20.656Z,1679350940.656 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:21.484Z,1679350941.484 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:25.915Z,1679350945.915 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:26.734Z,1679350946.734 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:31.183Z,1679350951.183 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:31.993Z,1679350951.993 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:36.436Z,1679350956.436 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:37.243Z,1679350957.243 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:41.691Z,1679350961.691 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:42.476Z,1679350962.476 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:46.930Z,1679350966.930 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:47.749Z,1679350967.749 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:52.185Z,1679350972.185 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:52.987Z,1679350972.987 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:57.438Z,1679350977.438 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:22:58.232Z,1679350978.232 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:02.691Z,1679350982.691 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:03.487Z,1679350983.487 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:07.937Z,1679350987.937 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:08.759Z,1679350988.759 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:13.187Z,1679350993.187 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:14.004Z,1679350994.004 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:18.439Z,1679350998.439 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:19.260Z,1679350999.260 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:23.690Z,1679351003.690 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:24.513Z,1679351004.513 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:24.870Z,1679351004.870 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-20T22:23:24.870Z,1679351004.870 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2023-03-20T22:23:28.954Z,1679351008.954 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:29.788Z,1679351009.788 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:34.203Z,1679351014.203 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:35.011Z,1679351015.011 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:39.460Z,1679351019.460 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:40.267Z,1679351020.267 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:44.731Z,1679351024.731 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:45.518Z,1679351025.518 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:49.943Z,1679351029.943 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:50.767Z,1679351030.767 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:55.222Z,1679351035.222 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:23:56.022Z,1679351036.022 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:24:00.471Z,1679351040.471 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:24:01.275Z,1679351041.275 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:24:05.737Z,1679351045.737 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:24:06.529Z,1679351046.529 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:24:10.963Z,1679351050.963 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:24:11.781Z,1679351051.781 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-20T22:24:13.341Z,1679351053.341 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-03-20T22:24:31.985Z,1679351071.985 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-03-20T22:24:31.985Z,1679351071.985 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:24:32.027Z,1679351072.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:24:32.413Z,1679351072.413 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:24:32.413Z,1679351072.413 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-03-20T22:25:06.726Z,1679351106.726 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-20T22:25:06.735Z,1679351106.735 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0000.0,1489.0,000
2023-03-20T22:25:09.154Z,1679351109.154 [DVL_micro](ERROR): only read 2 of 4 data items
2023-03-20T22:25:09.155Z,1679351109.155 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999..99,+9999.99
2023-03-20T22:27:02.689Z,1679351222.689 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-20T22:22:02.5Z
2023-03-20T22:27:02.690Z,1679351222.690 [Default:CheckIn:Read_GPS] Stopped
2023-03-20T22:27:02.690Z,1679351222.690 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-20T22:27:03.102Z,1679351223.102 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-20T22:27:11.490Z,1679351231.490 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230320T221820/Courier0004.lzma
2023-03-20T22:27:12.493Z,1679351232.493 [DataOverHttps](INFO): Moved sent file to Logs/20230320T221820/Courier0004.lzma.bak
2023-03-20T22:27:12.493Z,1679351232.493 [DataOverHttps](INFO): SBD MOMSN=17534385
2023-03-20T22:27:31.188Z,1679351251.188 [DataOverHttps](INFO): Sending 430 bytes from file Logs/20230320T221820/Express0005.lzma
2023-03-20T22:27:32.189Z,1679351252.189 [DataOverHttps](INFO): Moved sent file to Logs/20230320T221820/Express0005.lzma.bak
2023-03-20T22:27:32.189Z,1679351252.189 [DataOverHttps](INFO): SBD MOMSN=17534388
2023-03-20T22:27:33.004Z,1679351253.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-03-20T22:27:33.004Z,1679351253.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:27:33.095Z,1679351253.095 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:27:33.436Z,1679351253.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:27:33.436Z,1679351253.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-03-20T22:27:38.659Z,1679351258.659 [Default:CheckIn:Read_Iridium] Stopped
2023-03-20T22:27:38.659Z,1679351258.659 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-20T22:27:38.659Z,1679351258.659 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-20T22:28:04.897Z,1679351284.897 [CBIT](FAULT): WATER ALARM AUX.
2023-03-20T22:28:04.897Z,1679351284.897 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2023-03-20T22:28:29.928Z,1679351309.928 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-20T22:28:29.928Z,1679351309.928 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+22.3,0000.0,1489:WI,-03107,-02696,+01827,+00000,A
2023-03-20T22:28:56.608Z,1679351336.608 [CBIT](FAULT): WATER ALARM BOW.
2023-03-20T22:28:56.609Z,1679351336.609 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2023-03-20T22:28:57.015Z,1679351337.015 [CBIT](FAULT): WATER ALARM BOW.
2023-03-20T22:28:57.016Z,1679351337.016 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2023-03-20T22:28:57.429Z,1679351337.429 [CBIT](FAULT): WATER ALARM BOW.
2023-03-20T22:28:57.431Z,1679351337.431 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2023-03-20T22:28:57.849Z,1679351337.849 [CBIT](FAULT): WATER ALARM BOW.
2023-03-20T22:28:57.863Z,1679351337.863 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2023-03-20T22:28:57.863Z,1679351337.863 [CBIT](IMPORTANT): Powered off ESP with command: ! echo 0 > /dev/loadA6
2023-03-20T22:28:57.965Z,1679351337.965 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadA6
2023-03-20T22:28:58.280Z,1679351338.280 [CBIT](INFO): Critical error at 20230320T222857
2023-03-20T22:29:11.134Z,1679351351.134 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-20T22:29:11.134Z,1679351351.134 [NAL9602] Data Fault, FailCount= 1
2023-03-20T22:29:11.134Z,1679351351.134 [NAL9602](ERROR): Data Fault
2023-03-20T22:29:11.181Z,1679351351.181 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-20T22:29:11.540Z,1679351351.540 [NAL9602](INFO): Powering down
2023-03-20T22:29:12.372Z,1679351352.372 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-20T22:29:12.372Z,1679351352.372 [NAL9602] No Fault, FailCount= 1
2023-03-20T22:29:41.842Z,1679351381.842 [NAL9602](INFO): Powering up NAL9602
2023-03-20T22:29:52.749Z,1679351392.749 [NAL9602](INFO): NAL9602 initialized
2023-03-20T22:29:59.219Z,1679351399.219 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-20T22:29:59.219Z,1679351399.219 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,00.0,1489.0,000
2023-03-20T22:30:33.963Z,1679351433.963 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-03-20T22:30:33.963Z,1679351433.963 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:30:33.973Z,1679351433.973 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:30:34.398Z,1679351434.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:30:34.398Z,1679351434.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-03-20T22:31:28.695Z,1679351488.695 [CommandExec](IMPORTANT): got command quit
2023-03-20T22:31:29.699Z,1679351489.699 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:29.699Z,1679351489.699 [CommandExec](INFO): Uninitializing the command executive.
2023-03-20T22:31:29.699Z,1679351489.699 [CommandExec](INFO): Uninitializing the command scheduler.
2023-03-20T22:31:29.700Z,1679351489.700 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:29.708Z,1679351489.708 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-03-20T22:31:29.708Z,1679351489.708 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-03-20T22:31:29.708Z,1679351489.708 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:29.709Z,1679351489.709 [NavChartDb](INFO): Join timeout helper Thread ID is 976
2023-03-20T22:31:29.971Z,1679351489.971 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:29.971Z,1679351489.971 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:29.991Z,1679351489.991 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-03-20T22:31:29.991Z,1679351489.991 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:29.992Z,1679351489.992 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 977
2023-03-20T22:31:30.403Z,1679351490.403 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:30.403Z,1679351490.403 [WetLabsBB2FL](INFO): Powering down
2023-03-20T22:31:30.404Z,1679351490.404 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:30.415Z,1679351490.415 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-03-20T22:31:30.415Z,1679351490.415 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:30.415Z,1679351490.415 [CTD_Seabird](INFO): Join timeout helper Thread ID is 978
2023-03-20T22:31:30.711Z,1679351490.711 [CTD_Seabird](INFO): Powering down
2023-03-20T22:31:30.727Z,1679351490.727 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:30.731Z,1679351490.731 [CTD_Seabird](INFO): Powering down
2023-03-20T22:31:30.743Z,1679351490.743 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:30.759Z,1679351490.759 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-03-20T22:31:30.759Z,1679351490.759 [DAT ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:30.759Z,1679351490.759 [DAT](INFO): Join timeout helper Thread ID is 979
2023-03-20T22:31:30.823Z,1679351490.823 [DAT](INFO): Powering down
2023-03-20T22:31:30.896Z,1679351490.896 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:30.896Z,1679351490.896 [DAT](INFO): Powering down
2023-03-20T22:31:30.897Z,1679351490.897 [DAT ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:30.903Z,1679351490.903 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-03-20T22:31:30.903Z,1679351490.903 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:30.904Z,1679351490.904 [Radio_Surface](INFO): Join timeout helper Thread ID is 980
2023-03-20T22:31:31.031Z,1679351491.031 [Radio_Surface](INFO): Powering down
2023-03-20T22:31:31.032Z,1679351491.032 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:31.032Z,1679351491.032 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:31.045Z,1679351491.045 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-03-20T22:31:31.045Z,1679351491.045 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:31.046Z,1679351491.046 [Onboard](INFO): Join timeout helper Thread ID is 981
2023-03-20T22:31:31.971Z,1679351491.971 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-03-20T22:31:34.995Z,1679351494.995 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:34.995Z,1679351494.995 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:34.000Z,1679351495.000 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-03-20T22:31:34.000Z,1679351495.000 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:35.000Z,1679351495.000 [DataOverHttps](INFO): Join timeout helper Thread ID is 982
2023-03-20T22:31:35.987Z,1679351495.987 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:35.987Z,1679351495.987 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:35.000Z,1679351496.000 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-03-20T22:31:35.000Z,1679351496.000 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.000Z,1679351496.000 [BackseatComponent](INFO): Join timeout helper Thread ID is 983
2023-03-20T22:31:36.047Z,1679351496.047 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:36.047Z,1679351496.047 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.061Z,1679351496.061 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-03-20T22:31:36.062Z,1679351496.062 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.062Z,1679351496.062 [logger](INFO): Join timeout helper Thread ID is 984
2023-03-20T22:31:36.077Z,1679351496.077 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:36.077Z,1679351496.077 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.080Z,1679351496.080 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-03-20T22:31:36.080Z,1679351496.080 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.080Z,1679351496.080 [CommandLine](INFO): Join timeout helper Thread ID is 985
2023-03-20T22:31:36.135Z,1679351496.135 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:36.135Z,1679351496.135 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.140Z,1679351496.140 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-03-20T22:31:36.140Z,1679351496.140 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.140Z,1679351496.140 [CommandExec](INFO): Join timeout helper Thread ID is 986
2023-03-20T22:31:36.143Z,1679351496.143 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-03-20T22:31:36.143Z,1679351496.143 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.143Z,1679351496.143 [controlThread](INFO): Join timeout helper Thread ID is 987
2023-03-20T22:31:36.155Z,1679351496.155 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:31:36.155Z,1679351496.155 [controlThread](DEBUG): Uninitializing ControlThread
2023-03-20T22:31:36.156Z,1679351496.156 [AHRS_M2](INFO): Powering down
2023-03-20T22:31:36.299Z,1679351496.299 [DVL_micro](INFO): Powering down
2023-03-20T22:31:36.300Z,1679351496.300 [NAL9602](INFO): Powering down
2023-03-20T22:31:36.303Z,1679351496.303 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-03-20T22:31:36.304Z,1679351496.304 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-03-20T22:31:36.304Z,1679351496.304 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-03-20T22:31:36.305Z,1679351496.305 [MissionManager](INFO): Uninitializing Mission Default
2023-03-20T22:31:36.305Z,1679351496.305 [Default] Stopped
2023-03-20T22:31:36.305Z,1679351496.305 [Default](DEBUG): Aggregate::uninitialize Default
2023-03-20T22:31:36.305Z,1679351496.305 [Default:B.GoToSurface] Stopped
2023-03-20T22:31:36.305Z,1679351496.305 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-20T22:31:36.305Z,1679351496.305 [Default:CheckIn] Stopped
2023-03-20T22:31:36.305Z,1679351496.305 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-20T22:31:36.306Z,1679351496.306 [Default:CheckIn:C.Wait] Stopped
2023-03-20T22:31:36.306Z,1679351496.306 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-20T22:31:36.308Z,1679351496.308 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-03-20T22:31:36.309Z,1679351496.309 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-03-20T22:31:36.309Z,1679351496.309 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-03-20T22:31:36.309Z,1679351496.309 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-03-20T22:31:36.310Z,1679351496.310 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-03-20T22:31:36.310Z,1679351496.310 [BuoyancyServo](INFO): Powering down
2023-03-20T22:31:36.323Z,1679351496.323 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-03-20T22:31:36.323Z,1679351496.323 [ElevatorServo](INFO): Powering down
2023-03-20T22:31:36.324Z,1679351496.324 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-03-20T22:31:36.324Z,1679351496.324 [MassServo](INFO): Powering down
2023-03-20T22:31:36.325Z,1679351496.325 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-20T22:31:36.325Z,1679351496.325 [RudderServo](INFO): Powering down
2023-03-20T22:31:36.326Z,1679351496.326 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-03-20T22:31:36.326Z,1679351496.326 [ThrusterHE](INFO): Powering down
2023-03-20T22:31:36.327Z,1679351496.327 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-03-20T22:31:36.327Z,1679351496.327 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-03-20T22:31:36.328Z,1679351496.328 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-03-20T22:31:36.328Z,1679351496.328 [CBIT](DEBUG): Powering off loads.
2023-03-20T22:31:36.339Z,1679351496.339 [CBIT](DEBUG): Disabling WDT.
2023-03-20T22:31:36.351Z,1679351496.351 [CBIT](DEBUG): Opening all GF detection circuits.
2023-03-20T22:31:36.352Z,1679351496.352 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.400Z,1679351496.400 [DAT ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.405Z,1679351496.405 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.407Z,1679351496.407 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.415Z,1679351496.415 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.421Z,1679351496.421 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.475Z,1679351496.475 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.480Z,1679351496.480 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.502Z,1679351496.502 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:31:36.564Z,1679351496.564 [logger ThreadHandler](INFO): Thread cancelled.