2023-04-14T23:24:37.838Z,1681514677.838 [Supervisor](DEBUG): Initializing supervisor. 2023-04-14T23:24:37.842Z,1681514677.842 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-04-14T23:24:37.843Z,1681514677.843 [SyncHandler](INFO): Protected caller Thread ID is 1035 2023-04-14T23:24:37.843Z,1681514677.843 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-04-14T23:24:37.844Z,1681514677.844 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-04-14T23:24:37.845Z,1681514677.845 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1036 2023-04-14T23:24:37.849Z,1681514677.849 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-04-14T23:24:37.866Z,1681514677.866 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-04-14T23:24:37.867Z,1681514677.867 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-04-14T23:24:37.867Z,1681514677.867 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1037 2023-04-14T23:24:37.871Z,1681514677.871 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-04-14T23:24:37.872Z,1681514677.872 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-04-14T23:24:37.873Z,1681514677.873 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1038 2023-04-14T23:24:37.875Z,1681514677.875 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-04-14T23:24:37.876Z,1681514677.876 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-04-14T23:24:37.876Z,1681514677.876 [logger ThreadHandler](INFO): Protected caller Thread ID is 1039 2023-04-14T23:24:37.880Z,1681514677.880 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-04-14T23:24:37.880Z,1681514677.880 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-04-14T23:24:37.885Z,1681514677.885 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-04-14T23:24:37.981Z,1681514677.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-04-14T23:24:37.983Z,1681514677.983 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-04-14T23:24:38.198Z,1681514678.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-04-14T23:24:38.200Z,1681514678.200 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-04-14T23:24:38.453Z,1681514678.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-04-14T23:24:38.454Z,1681514678.454 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-04-14T23:24:38.561Z,1681514678.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-04-14T23:24:38.562Z,1681514678.562 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-04-14T23:24:39.243Z,1681514679.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-04-14T23:24:39.243Z,1681514679.243 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-04-14T23:24:39.718Z,1681514679.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-04-14T23:24:39.719Z,1681514679.719 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-04-14T23:24:40.201Z,1681514680.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-04-14T23:24:40.202Z,1681514680.202 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-04-14T23:24:40.598Z,1681514680.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-04-14T23:24:40.598Z,1681514680.598 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-04-14T23:24:40.923Z,1681514680.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-04-14T23:24:40.924Z,1681514680.924 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-04-14T23:24:41.647Z,1681514681.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-04-14T23:24:41.647Z,1681514681.647 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-04-14T23:24:41.870Z,1681514681.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-04-14T23:24:41.870Z,1681514681.870 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-04-14T23:24:42.009Z,1681514682.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-04-14T23:24:42.010Z,1681514682.010 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-04-14T23:24:42.091Z,1681514682.091 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-04-14T23:24:42.171Z,1681514682.171 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-04-14T23:24:42.360Z,1681514682.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-04-14T23:24:42.361Z,1681514682.361 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-04-14T23:24:42.585Z,1681514682.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-04-14T23:24:42.587Z,1681514682.587 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2023-04-14T23:24:42.590Z,1681514682.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2023-04-14T23:24:42.673Z,1681514682.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2023-04-14T23:24:42.935Z,1681514682.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-04-14T23:24:42.935Z,1681514682.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2023-04-14T23:24:43.033Z,1681514683.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2023-04-14T23:24:43.190Z,1681514683.190 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2023-04-14T23:24:43.366Z,1681514683.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2023-04-14T23:24:43.452Z,1681514683.452 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2023-04-14T23:24:43.561Z,1681514683.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2023-04-14T23:24:43.664Z,1681514683.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2023-04-14T23:24:43.818Z,1681514683.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2023-04-14T23:24:43.913Z,1681514683.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2023-04-14T23:24:44.010Z,1681514684.010 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-04-14T23:24:44.029Z,1681514684.029 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-04-14T23:24:44.216Z,1681514684.216 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-04-14T23:24:44.217Z,1681514684.217 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-04-14T23:24:44.380Z,1681514684.380 [BuoyancyServo] Loaded 2023-04-14T23:24:44.380Z,1681514684.380 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-04-14T23:24:44.401Z,1681514684.401 [ElevatorServo] Loaded 2023-04-14T23:24:44.401Z,1681514684.401 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-04-14T23:24:44.421Z,1681514684.421 [MassServo] Loaded 2023-04-14T23:24:44.421Z,1681514684.421 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-04-14T23:24:44.441Z,1681514684.441 [RudderServo] Loaded 2023-04-14T23:24:44.441Z,1681514684.441 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-04-14T23:24:44.457Z,1681514684.457 [ThrusterHE] Loaded 2023-04-14T23:24:44.457Z,1681514684.457 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-04-14T23:24:44.457Z,1681514684.457 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-04-14T23:24:44.459Z,1681514684.459 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-04-14T23:24:44.632Z,1681514684.632 [SBIT](DEBUG): Construct Startup Built In Test. 2023-04-14T23:24:44.647Z,1681514684.647 [SBIT] Loaded 2023-04-14T23:24:44.647Z,1681514684.647 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-04-14T23:24:44.650Z,1681514684.650 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-04-14T23:24:44.670Z,1681514684.670 [IBIT] Loaded 2023-04-14T23:24:44.670Z,1681514684.670 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-04-14T23:24:44.676Z,1681514684.676 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-04-14T23:24:44.890Z,1681514684.890 [CBIT] Loaded 2023-04-14T23:24:44.890Z,1681514684.890 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-04-14T23:24:44.891Z,1681514684.891 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-04-14T23:24:44.892Z,1681514684.892 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-04-14T23:24:45.106Z,1681514685.106 [CTD_Seabird] Loaded 2023-04-14T23:24:45.106Z,1681514685.106 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-04-14T23:24:45.108Z,1681514685.108 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406644E0 2023-04-14T23:24:45.108Z,1681514685.108 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1121 2023-04-14T23:24:45.139Z,1681514685.139 [ESPComponent] Loaded 2023-04-14T23:24:45.140Z,1681514685.140 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-04-14T23:24:45.159Z,1681514685.159 [PAR_Licor] Loaded 2023-04-14T23:24:45.159Z,1681514685.159 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-04-14T23:24:45.189Z,1681514685.189 [WetLabsBB2FL] Loaded 2023-04-14T23:24:45.190Z,1681514685.190 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-04-14T23:24:45.191Z,1681514685.191 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406944E0 2023-04-14T23:24:45.191Z,1681514685.191 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1122 2023-04-14T23:24:45.192Z,1681514685.192 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-04-14T23:24:45.194Z,1681514685.194 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-04-14T23:24:45.264Z,1681514685.264 [DepthRateCalculator] Loaded 2023-04-14T23:24:45.265Z,1681514685.265 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-04-14T23:24:45.270Z,1681514685.270 [PitchRateCalculator] Loaded 2023-04-14T23:24:45.270Z,1681514685.270 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-04-14T23:24:45.280Z,1681514685.280 [SpeedCalculator] Loaded 2023-04-14T23:24:45.280Z,1681514685.280 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-04-14T23:24:45.294Z,1681514685.294 [VerticalHomogeneityIndexCalculator] Loaded 2023-04-14T23:24:45.295Z,1681514685.295 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread. 2023-04-14T23:24:45.299Z,1681514685.299 [YawRateCalculator] Loaded 2023-04-14T23:24:45.299Z,1681514685.299 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-04-14T23:24:45.318Z,1681514685.318 [ElevatorOffsetCalculator] Loaded 2023-04-14T23:24:45.319Z,1681514685.319 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-04-14T23:24:45.319Z,1681514685.319 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-04-14T23:24:45.320Z,1681514685.320 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-04-14T23:24:45.446Z,1681514685.446 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-04-14T23:24:45.447Z,1681514685.447 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-04-14T23:24:45.514Z,1681514685.514 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-04-14T23:24:45.515Z,1681514685.515 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-04-14T23:24:45.670Z,1681514685.670 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-04-14T23:24:45.670Z,1681514685.670 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-04-14T23:24:45.684Z,1681514685.684 [NavChart] Loaded 2023-04-14T23:24:45.684Z,1681514685.684 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-04-14T23:24:45.690Z,1681514685.690 [UniversalFixResidualReporter] Loaded 2023-04-14T23:24:45.690Z,1681514685.690 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-04-14T23:24:45.691Z,1681514685.691 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-04-14T23:24:45.691Z,1681514685.691 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-04-14T23:24:46.498Z,1681514686.498 [AHRS_M2] Loaded 2023-04-14T23:24:46.498Z,1681514686.498 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-04-14T23:24:46.747Z,1681514686.747 [BackseatComponent] Loaded 2023-04-14T23:24:46.747Z,1681514686.747 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-04-14T23:24:46.748Z,1681514686.748 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0 2023-04-14T23:24:46.748Z,1681514686.748 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1124 2023-04-14T23:24:46.751Z,1681514686.751 [LcmUniversalReporter] Loaded 2023-04-14T23:24:46.751Z,1681514686.751 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-04-14T23:24:48.051Z,1681514688.051 [BPC1] Loaded 2023-04-14T23:24:48.051Z,1681514688.051 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-04-14T23:24:48.194Z,1681514688.194 [DataOverHttps] Loaded 2023-04-14T23:24:48.194Z,1681514688.194 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-04-14T23:24:48.195Z,1681514688.195 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A544E0 2023-04-14T23:24:48.196Z,1681514688.196 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1125 2023-04-14T23:24:48.216Z,1681514688.216 [Depth_Keller] Loaded 2023-04-14T23:24:48.216Z,1681514688.216 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-04-14T23:24:48.221Z,1681514688.221 [DropWeight] Loaded 2023-04-14T23:24:48.222Z,1681514688.222 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-04-14T23:24:48.260Z,1681514688.260 [DVL_micro] Loaded 2023-04-14T23:24:48.260Z,1681514688.260 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2023-04-14T23:24:48.320Z,1681514688.320 [NAL9602] Loaded 2023-04-14T23:24:48.320Z,1681514688.320 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-04-14T23:24:48.348Z,1681514688.348 [Onboard] Loaded 2023-04-14T23:24:48.348Z,1681514688.348 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-04-14T23:24:48.349Z,1681514688.349 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A844E0 2023-04-14T23:24:48.349Z,1681514688.349 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1126 2023-04-14T23:24:48.362Z,1681514688.362 [Power24vConverter] Loaded 2023-04-14T23:24:48.362Z,1681514688.362 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-04-14T23:24:48.376Z,1681514688.376 [Radio_Surface] Loaded 2023-04-14T23:24:48.376Z,1681514688.376 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-04-14T23:24:48.377Z,1681514688.377 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AB44E0 2023-04-14T23:24:48.377Z,1681514688.377 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1127 2023-04-14T23:24:48.470Z,1681514688.470 [DAT] Loaded 2023-04-14T23:24:48.470Z,1681514688.470 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-04-14T23:24:48.471Z,1681514688.471 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AE44E0 2023-04-14T23:24:48.472Z,1681514688.472 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1128 2023-04-14T23:24:48.472Z,1681514688.472 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-04-14T23:24:48.473Z,1681514688.473 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-04-14T23:24:48.571Z,1681514688.571 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.050012 degC 2023-04-14T23:24:48.571Z,1681514688.571 [StratificationFrontDetector](INFO): (re)initializing 2023-04-14T23:24:48.571Z,1681514688.571 [StratificationFrontDetector] Loaded 2023-04-14T23:24:48.571Z,1681514688.571 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2023-04-14T23:24:48.572Z,1681514688.572 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-04-14T23:24:48.573Z,1681514688.573 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-04-14T23:24:48.642Z,1681514688.642 [VerticalControl](DEBUG): Construct VerticalControl. 2023-04-14T23:24:48.693Z,1681514688.693 [VerticalControl] Loaded 2023-04-14T23:24:48.693Z,1681514688.693 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-04-14T23:24:48.696Z,1681514688.696 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-04-14T23:24:48.732Z,1681514688.732 [HorizontalControl] Loaded 2023-04-14T23:24:48.732Z,1681514688.732 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-04-14T23:24:48.735Z,1681514688.735 [SpeedControl](DEBUG): Construct SpeedControl. 2023-04-14T23:24:48.737Z,1681514688.737 [SpeedControl] Loaded 2023-04-14T23:24:48.737Z,1681514688.737 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-04-14T23:24:48.740Z,1681514688.740 [LoopControl](DEBUG): Construct LoopControl. 2023-04-14T23:24:48.740Z,1681514688.740 [LoopControl] Loaded 2023-04-14T23:24:48.740Z,1681514688.740 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-04-14T23:24:48.741Z,1681514688.741 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-04-14T23:24:48.741Z,1681514688.741 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-04-14T23:24:48.757Z,1681514688.757 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-04-14T23:24:48.758Z,1681514688.758 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-04-14T23:24:49.124Z,1681514689.124 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-04-14T23:24:49.130Z,1681514689.130 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-04-14T23:24:49.133Z,1681514689.133 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-04-14T23:24:49.144Z,1681514689.144 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-04-14T23:24:49.145Z,1681514689.145 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0 2023-04-14T23:24:49.145Z,1681514689.145 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1129 2023-04-14T23:24:49.150Z,1681514689.150 [Supervisor](INFO): Main Thread ID is 1034 2023-04-14T23:24:49.150Z,1681514689.150 [Supervisor](DEBUG): Running supervisor. 2023-04-14T23:24:49.151Z,1681514689.151 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1130 2023-04-14T23:24:49.151Z,1681514689.151 [CommandExec](INFO): Initializing the command executive. 2023-04-14T23:24:49.153Z,1681514689.153 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1131 2023-04-14T23:24:49.155Z,1681514689.155 [controlThread ThreadHandler](INFO): Handler Thread ID is 1132 2023-04-14T23:24:49.155Z,1681514689.155 [controlThread](DEBUG): Initializing ControlThread 2023-04-14T23:24:49.157Z,1681514689.157 [SBIT](INFO): Initialize SBIT Component. 2023-04-14T23:24:49.157Z,1681514689.157 [SBIT](IMPORTANT): git: 2023-04-14_A 2023-04-14T23:24:49.158Z,1681514689.158 [SBIT](INFO): git hash: a5e7240676c499658609ad29f2edbd696bbbdf6d 2023-04-14T23:24:49.158Z,1681514689.158 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-04-14T23:24:49.159Z,1681514689.159 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-04-14T23:24:49.160Z,1681514689.160 [SBIT](INFO): Beginning SBIT in 79.000000 seconds. 2023-04-14T23:24:49.161Z,1681514689.161 [IBIT](INFO): Initialize IBIT Component. 2023-04-14T23:24:49.162Z,1681514689.162 [CBIT](DEBUG): Initialize CBIT Component. 2023-04-14T23:24:49.163Z,1681514689.163 [logger ThreadHandler](INFO): Handler Thread ID is 1133 2023-04-14T23:24:49.176Z,1681514689.176 [CBIT](DEBUG): Initialized mux pins. 2023-04-14T23:24:49.176Z,1681514689.176 [CBIT](DEBUG): Initializing the watchdog timer. 2023-04-14T23:24:49.188Z,1681514689.188 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1134 2023-04-14T23:24:49.189Z,1681514689.189 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-04-14T23:24:49.193Z,1681514689.193 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1136 2023-04-14T23:24:49.196Z,1681514689.196 [WetLabsBB2FL](INFO): Powering up 2023-04-14T23:24:49.197Z,1681514689.197 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1138 2023-04-14T23:24:49.200Z,1681514689.200 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-04-14T23:24:49.200Z,1681514689.200 [CBIT](DEBUG): Initializing heartbeat. 2023-04-14T23:24:49.214Z,1681514689.214 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1139 2023-04-14T23:24:49.215Z,1681514689.215 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-04-14T23:24:49.228Z,1681514689.228 [Onboard ThreadHandler](INFO): Handler Thread ID is 1140 2023-04-14T23:24:49.245Z,1681514689.245 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1141 2023-04-14T23:24:49.268Z,1681514689.268 [DAT ThreadHandler](INFO): Handler Thread ID is 1142 2023-04-14T23:24:49.272Z,1681514689.272 [CBIT](DEBUG): Deactivating GF circuits. 2023-04-14T23:24:49.272Z,1681514689.272 [CBIT](DEBUG): Deactivating emergency mode. 2023-04-14T23:24:49.273Z,1681514689.273 [DAT](INFO): Powering up 2023-04-14T23:24:49.273Z,1681514689.273 [DAT](DEBUG): Initializing DAT. 2023-04-14T23:24:49.276Z,1681514689.276 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1144 2023-04-14T23:24:49.285Z,1681514689.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-04-14T23:24:49.285Z,1681514689.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-04-14T23:24:49.285Z,1681514689.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-04-14T23:24:49.285Z,1681514689.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-04-14T23:24:49.285Z,1681514689.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-04-14T23:24:49.285Z,1681514689.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-04-14T23:24:49.286Z,1681514689.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-04-14T23:24:49.286Z,1681514689.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-04-14T23:24:49.286Z,1681514689.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000 2023-04-14T23:24:49.286Z,1681514689.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000 2023-04-14T23:24:49.312Z,1681514689.312 [CBIT](DEBUG): Backplane powered. 2023-04-14T23:24:49.316Z,1681514689.316 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-04-14T23:24:49.316Z,1681514689.316 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-04-14T23:24:49.317Z,1681514689.317 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-04-14T23:24:49.317Z,1681514689.317 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing 2023-04-14T23:24:49.318Z,1681514689.318 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-04-14T23:24:49.318Z,1681514689.318 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-04-14T23:24:49.319Z,1681514689.319 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-04-14T23:24:49.320Z,1681514689.320 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-04-14T23:24:49.338Z,1681514689.338 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-04-14T23:24:49.344Z,1681514689.344 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-04-14T23:24:49.345Z,1681514689.345 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-04-14T23:24:49.346Z,1681514689.346 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-04-14T23:24:49.346Z,1681514689.346 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-04-14T23:24:49.388Z,1681514689.388 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-04-14T23:24:49.421Z,1681514689.421 [MissionManager](DEBUG): 2023-04-14T23:24:49.422Z,1681514689.422 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-04-14T23:24:49.503Z,1681514689.503 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-04-14T23:24:49.504Z,1681514689.504 [Default:A.Wait](DEBUG): Construct Wait. 2023-04-14T23:24:49.506Z,1681514689.506 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-04-14T23:24:49.555Z,1681514689.555 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-04-14T23:24:49.558Z,1681514689.558 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-04-14T23:24:49.587Z,1681514689.587 [Default:E.Execute](DEBUG): Construct Execute. 2023-04-14T23:24:49.607Z,1681514689.607 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-04-14T23:24:49.612Z,1681514689.612 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,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-04-14T23:24:49.623Z,1681514689.623 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-04-14T23:24:49.652Z,1681514689.652 [Radio_Surface](INFO): Powering up 2023-04-14T23:24:49.905Z,1681514689.905 [Power24vConverter](INFO): Powering up. 2023-04-14T23:24:49.938Z,1681514689.938 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-04-14T23:24:49.944Z,1681514689.944 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-04-14T23:24:49.945Z,1681514689.945 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-04-14T23:24:49.952Z,1681514689.952 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-04-14T23:24:49.953Z,1681514689.953 [MassServo](DEBUG): Initializing EZServoServo. 2023-04-14T23:24:49.960Z,1681514689.960 [MassServo](DEBUG): Initializing MassServo. 2023-04-14T23:24:49.961Z,1681514689.961 [RudderServo](DEBUG): Initializing EZServoServo. 2023-04-14T23:24:49.968Z,1681514689.968 [RudderServo](DEBUG): Initializing RudderServo. 2023-04-14T23:24:49.969Z,1681514689.969 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-04-14T23:24:49.976Z,1681514689.976 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-04-14T23:24:50.433Z,1681514690.433 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.537330 decibar, -0.533367 m 2023-04-14T23:24:51.246Z,1681514691.246 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.580732 decibar, -0.576448 m 2023-04-14T23:24:51.300Z,1681514691.300 [WetLabsBB2FL](INFO): Powering down 2023-04-14T23:24:52.461Z,1681514692.461 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 3 samples 2023-04-14T23:24:52.461Z,1681514692.461 [Depth_Keller] Data Fault, FailCount= 1 2023-04-14T23:24:52.461Z,1681514692.461 [Depth_Keller](ERROR): Data Fault 2023-04-14T23:24:52.600Z,1681514692.600 [CBIT](ERROR): Data Fault in component: Depth_Keller 2023-04-14T23:24:52.600Z,1681514692.600 [CBIT](INFO): Clearing failed state for component Depth_Keller 2023-04-14T23:24:52.600Z,1681514692.600 [Depth_Keller] No Fault, FailCount= 0 2023-04-14T23:24:53.284Z,1681514693.284 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 3 samples 2023-04-14T23:24:53.285Z,1681514693.285 [Depth_Keller] Data Fault, FailCount= 1 2023-04-14T23:24:53.285Z,1681514693.285 [Depth_Keller](ERROR): Data Fault 2023-04-14T23:24:53.444Z,1681514693.444 [CBIT](ERROR): Data Fault in component: Depth_Keller 2023-04-14T23:24:53.444Z,1681514693.444 [CBIT](INFO): Clearing failed state for component Depth_Keller 2023-04-14T23:24:53.444Z,1681514693.444 [Depth_Keller] No Fault, FailCount= 0 2023-04-14T23:24:54.160Z,1681514694.160 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 3 samples 2023-04-14T23:24:54.160Z,1681514694.160 [Depth_Keller] Data Fault, FailCount= 1 2023-04-14T23:24:54.161Z,1681514694.161 [Depth_Keller](ERROR): Data Fault 2023-04-14T23:24:54.328Z,1681514694.328 [CBIT](ERROR): Data Fault in component: Depth_Keller 2023-04-14T23:24:54.328Z,1681514694.328 [CBIT](INFO): Clearing failed state for component Depth_Keller 2023-04-14T23:24:54.328Z,1681514694.328 [Depth_Keller] No Fault, FailCount= 0 2023-04-14T23:24:55.513Z,1681514695.513 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-04-14T23:25:01.648Z,1681514701.648 [DAT](INFO): DAT read: 2023-04-14T23:25:01.650Z,1681514701.650 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-04-14T23:25:03.161Z,1681514703.161 [DAT](INFO): DAT read: MF Frequency Band 2023-04-14T23:25:03.162Z,1681514703.162 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-04-14T23:25:03.162Z,1681514703.162 [DAT](INFO): DAT read: Apr 14 2023 23:24:58 2023-04-14T23:25:04.421Z,1681514704.421 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-04-14T23:25:04.422Z,1681514704.422 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2023-04-14T23:25:04.422Z,1681514704.422 [DAT](INFO): commRate: 1200 2023-04-14T23:25:06.488Z,1681514706.488 [DAT](INFO): entering command mode 2023-04-14T23:25:06.688Z,1681514706.688 [DAT](INFO): DAT read: 2023-04-14T23:25:06.689Z,1681514706.689 [DAT](INFO): DAT read: user:1> 2023-04-14T23:25:06.689Z,1681514706.689 [DAT](INFO): setting verbose to 3 2023-04-14T23:25:06.942Z,1681514706.942 [DAT](INFO): DAT read: user:1> 2023-04-14T23:25:06.946Z,1681514706.946 [DAT](INFO): DAT read: Verbose | 3 2023-04-14T23:25:06.946Z,1681514706.946 [DAT](INFO): set verbose to 3 2023-04-14T23:25:06.947Z,1681514706.947 [DAT](INFO): setting DatVerbose to 27440 2023-04-14T23:25:07.192Z,1681514707.192 [DAT](INFO): DAT read: user:2> 2023-04-14T23:25:07.193Z,1681514707.193 [DAT](INFO): DAT read: DatVerbose | 27440 2023-04-14T23:25:07.194Z,1681514707.194 [DAT](INFO): set DatVerbose to 27440 2023-04-14T23:25:07.194Z,1681514707.194 [DAT](INFO): setting transmit power to 8 2023-04-14T23:25:07.446Z,1681514707.446 [DAT](INFO): DAT read: user:3> 2023-04-14T23:25:07.447Z,1681514707.447 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-04-14T23:25:07.448Z,1681514707.448 [DAT](INFO): set transmit power to 8 2023-04-14T23:25:07.448Z,1681514707.448 [DAT](INFO): setting local address to 4 2023-04-14T23:25:07.696Z,1681514707.696 [DAT](INFO): DAT read: user:4> 2023-04-14T23:25:07.698Z,1681514707.698 [DAT](INFO): DAT read: LocalAddr | 4 2023-04-14T23:25:07.698Z,1681514707.698 [DAT](INFO): set local address to 4 2023-04-14T23:25:07.699Z,1681514707.699 [DAT](INFO): Setting time to: 23:25:7 And date to:4/14/2023 2023-04-14T23:25:07.948Z,1681514707.948 [DAT](INFO): DAT read: user:5> 2023-04-14T23:25:07.949Z,1681514707.949 [DAT](INFO): DAT read: Fri Apr 14, 2023 23:25:07 2023-04-14T23:25:07.950Z,1681514707.950 [DAT](INFO): Local DAT time set to Fri Apr 14, 2023 23:25:07 2023-04-14T23:25:18.322Z,1681514718.322 [NAL9602](INFO): Powering up NAL9602 2023-04-14T23:25:29.223Z,1681514729.223 [NAL9602](INFO): NAL9602 initialized 2023-04-14T23:26:08.828Z,1681514768.828 [SBIT](IMPORTANT): Beginning Startup BIT 2023-04-14T23:26:08.832Z,1681514768.832 [CBIT](IMPORTANT): Beginning ground fault scan 2023-04-14T23:26:19.787Z,1681514779.787 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005841 CHAN A1 (24V): 0.003103 CHAN A2 (12V): -0.005644 CHAN A3 (5V): -0.003105 CHAN B0 (3.3V): -0.001573 CHAN B1 (3.15aV): -0.001072 CHAN B2 (3.15bV): -0.001303 CHAN B3 (GND): -0.000589 OPEN: 0.001151 Full Scale: +/- 1 mA 2023-04-14T23:26:25.032Z,1681514785.032 [CommandExec](IMPORTANT): got command failComponent 2023-04-14T23:26:25.032Z,1681514785.032 [CommandExec](IMPORTANT): Failed components: 2023-04-14T23:26:25.033Z,1681514785.033 [CommandExec](IMPORTANT): No failed Components. 2023-04-14T23:26:41.075Z,1681514801.075 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005332 2023-04-14T23:26:53.564Z,1681514813.564 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute 2023-04-14T23:26:53.568Z,1681514813.568 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2023-04-14T23:26:53.810Z,1681514813.810 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-04-14T23:27:02.242Z,1681514822.242 [SBIT](FAULT): Mass: EXPECTED:-0.014830 ACTUAL:-0.015000 2023-04-14T23:27:02.243Z,1681514822.243 [SBIT](FAULT): Control surface position failure. 2023-04-14T23:27:02.640Z,1681514822.640 [SBIT](CRITICAL): SBIT FAILED 2023-04-14T23:27:02.641Z,1681514822.641 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-04-14T23:27:02.641Z,1681514822.641 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): Depth_Keller.offset=-0.8 decibar; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=4 minute; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_salinity_homogeneity_index 0.050000 practical_salinity_unit; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff470nm; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff650nm; 2023-04-14T23:27:02.642Z,1681514822.642 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool; 2023-04-14T23:27:02.643Z,1681514822.643 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius; 2023-04-14T23:27:02.643Z,1681514822.643 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count; 2023-04-14T23:27:02.643Z,1681514822.643 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=79.764107 cubic_centimeter; 2023-04-14T23:27:02.643Z,1681514822.643 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.830472 millimeter; 2023-04-14T23:27:02.643Z,1681514822.643 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter; 2023-04-14T23:27:02.643Z,1681514822.643 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter; 2023-04-14T23:27:02.643Z,1681514822.643 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool; 2023-04-14T23:27:02.645Z,1681514822.645 [CommandExec](FAULT): Scheduling is paused 2023-04-14T23:27:02.646Z,1681514822.646 [CBIT](INFO): Critical error at 20230414T232702 2023-04-14T23:27:02.646Z,1681514822.646 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-04-14T23:27:02.964Z,1681514822.964 [MissionManager](IMPORTANT): Started mission Startup 2023-04-14T23:27:02.965Z,1681514822.965 [Startup] Running Loop=1 2023-04-14T23:27:02.965Z,1681514822.965 [Startup](DEBUG): Aggregate::initialize Startup 2023-04-14T23:27:02.965Z,1681514822.965 [Startup:A.GoToSurface] Running Loop=1 2023-04-14T23:27:02.965Z,1681514822.965 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-04-14T23:27:02.965Z,1681514822.965 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-04-14T23:27:02.966Z,1681514822.966 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-04-14T23:27:02.967Z,1681514822.967 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-04-14T23:27:02.967Z,1681514822.967 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-04-14T23:27:02.994Z,1681514822.994 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-04-14T23:27:02.996Z,1681514822.996 [Startup:StartupSatComms] Running Loop=1 2023-04-14T23:27:02.996Z,1681514822.996 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-04-14T23:27:02.996Z,1681514822.996 [Startup:StartupSatComms:A] Running Loop=1 2023-04-14T23:27:03.484Z,1681514823.484 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-04-14T23:27:06.272Z,1681514826.272 [NAL9602](INFO): SBD MO Status=2, MOMSN=2443, MT Status=2, MTMSN=0 2023-04-14T23:27:06.272Z,1681514826.272 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-14T23:27:11.861Z,1681514831.861 [CommandExec](IMPORTANT): got command maintain clear 2023-04-14T23:27:11.969Z,1681514831.969 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,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-04-14T23:27:34.070Z,1681514854.070 [NAL9602](INFO): SBD MO Status=2, MOMSN=2443, MT Status=2, MTMSN=0 2023-04-14T23:27:34.070Z,1681514854.070 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-14T23:27:49.445Z,1681514869.445 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-04-14T23:27:49.445Z,1681514869.445 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-04-14T23:27:49.456Z,1681514869.456 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-04-14T23:27:49.819Z,1681514869.819 [NAL9602](INFO): SBD MO Status=2, MOMSN=2443, MT Status=2, MTMSN=0 2023-04-14T23:27:49.819Z,1681514869.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-14T23:27:49.851Z,1681514869.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-04-14T23:27:49.851Z,1681514869.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-04-14T23:27:59.537Z,1681514879.537 [NAL9602](INFO): SBD MO Status=0, MOMSN=2443, MT Status=0, MTMSN=0 2023-04-14T23:27:59.537Z,1681514879.537 [NAL9602](INFO): No messages in MT queue 2023-04-14T23:28:03.182Z,1681514883.182 [Startup:StartupSatComms:A](INFO): Timed out from 2023-04-14T23:27:02.0Z 2023-04-14T23:28:03.182Z,1681514883.182 [Startup:StartupSatComms:A] Stopped 2023-04-14T23:28:03.182Z,1681514883.182 [Startup:StartupSatComms:B] Running Loop=1 2023-04-14T23:28:03.574Z,1681514883.574 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-04-14T23:28:04.449Z,1681514884.449 [DVL_micro](ERROR): only read 0 of 4 data items 2023-04-14T23:28:04.450Z,1681514884.450 [DVL_micro](ERROR): Failed to parse: :BI-00125,-00453,+00000,I 2023-04-14T23:28:10.792Z,1681514890.792 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20230414T232437/Courier0000.lzma 2023-04-14T23:28:11.795Z,1681514891.795 [DataOverHttps](INFO): Moved sent file to Logs/20230414T232437/Courier0000.lzma.bak 2023-04-14T23:28:11.795Z,1681514891.795 [DataOverHttps](INFO): SBD MOMSN=18222862 2023-04-14T23:28:27.777Z,1681514907.777 [DataOverHttps](INFO): Sending 1266 bytes from file Logs/20230414T232437/Express0001.lzma 2023-04-14T23:28:28.777Z,1681514908.777 [DataOverHttps](INFO): Moved sent file to Logs/20230414T232437/Express0001.lzma.bak 2023-04-14T23:28:28.778Z,1681514908.778 [DataOverHttps](INFO): SBD MOMSN=18222871 2023-04-14T23:28:29.851Z,1681514909.851 [Startup:StartupSatComms:B] Stopped 2023-04-14T23:28:29.851Z,1681514909.851 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-04-14T23:28:29.851Z,1681514909.851 [Startup:StartupSatComms] Stopped 2023-04-14T23:28:29.852Z,1681514909.852 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-04-14T23:28:29.852Z,1681514909.852 [Startup](INFO): Completed Startup 2023-04-14T23:28:29.853Z,1681514909.853 [MissionManager](INFO): Startup is completed. 2023-04-14T23:28:29.853Z,1681514909.853 [MissionManager](INFO): Uninitializing Mission Startup 2023-04-14T23:28:29.853Z,1681514909.853 [Startup] Stopped 2023-04-14T23:28:29.853Z,1681514909.853 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-04-14T23:28:29.853Z,1681514909.853 [Startup:A.GoToSurface] Stopped 2023-04-14T23:28:29.853Z,1681514909.853 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-04-14T23:28:30.237Z,1681514910.237 [MissionManager](IMPORTANT): Started mission Default 2023-04-14T23:28:30.238Z,1681514910.238 [Default] Running Loop=1 2023-04-14T23:28:30.238Z,1681514910.238 [Default](DEBUG): Aggregate::initialize Default 2023-04-14T23:28:30.238Z,1681514910.238 [Default:B.GoToSurface] Running Loop=1 2023-04-14T23:28:30.238Z,1681514910.238 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-04-14T23:28:30.238Z,1681514910.238 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-04-14T23:28:30.238Z,1681514910.238 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-04-14T23:28:30.239Z,1681514910.239 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-04-14T23:28:30.239Z,1681514910.239 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-04-14T23:28:30.239Z,1681514910.239 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-04-14T23:28:30.241Z,1681514910.241 [Default:A.Wait] Running Loop=1 2023-04-14T23:28:30.241Z,1681514910.241 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-04-14T23:28:43.562Z,1681514923.562 [Default:A.Wait](INFO): Done Waiting. 2023-04-14T23:28:43.562Z,1681514923.562 [Default:A.Wait] Stopped 2023-04-14T23:28:43.562Z,1681514923.562 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-04-14T23:28:43.990Z,1681514923.990 [Default:CheckIn] Running Loop=1 2023-04-14T23:28:43.990Z,1681514923.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-14T23:28:43.990Z,1681514923.990 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-14T23:28:44.391Z,1681514924.391 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-04-14T23:30:00.068Z,1681515000.068 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute 2023-04-14T23:30:00.070Z,1681515000.070 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2023-04-14T23:30:00.373Z,1681515000.373 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-04-14T23:30:06.484Z,1681515006.484 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:08.480Z,1681515008.480 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:08.932Z,1681515008.932 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:09.301Z,1681515009.301 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:09.688Z,1681515009.688 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:10.042Z,1681515010.042 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-04-14T23:30:10.042Z,1681515010.042 [ThrusterHE](INFO): Powering down 2023-04-14T23:30:10.840Z,1681515010.840 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-04-14T23:30:10.957Z,1681515010.957 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-04-14T23:30:23.428Z,1681515023.428 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:23.848Z,1681515023.848 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:24.212Z,1681515024.212 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:24.620Z,1681515024.620 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:25.012Z,1681515025.012 [ThrusterHE](IMPORTANT): Status lock Detected. 2023-04-14T23:30:25.386Z,1681515025.386 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-04-14T23:30:25.386Z,1681515025.386 [ThrusterHE](INFO): Powering down 2023-04-14T23:30:26.212Z,1681515026.212 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-04-14T23:30:26.333Z,1681515026.333 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-04-14T23:30:34.945Z,1681515034.945 [CommandExec](IMPORTANT): got command maintain clear 2023-04-14T23:30:35.205Z,1681515035.205 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,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-04-14T23:30:43.977Z,1681515043.977 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-14T23:30:43.977Z,1681515043.977 [DVL_micro](ERROR): Failed to parse: :BI,+00106,-0014,+00000,I 2023-04-14T23:30:50.456Z,1681515050.456 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-04-14T23:30:50.456Z,1681515050.456 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-04-14T23:30:50.466Z,1681515050.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-04-14T23:30:50.910Z,1681515050.910 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-04-14T23:30:50.910Z,1681515050.910 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-04-14T23:30:58.897Z,1681515058.897 [CommandExec](IMPORTANT): got command strobe off 2023-04-14T23:30:58.897Z,1681515058.897 [CommandExec](IMPORTANT): Deactivating strobe 2023-04-14T23:32:18.097Z,1681515138.097 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-04-14T23:32:18.100Z,1681515138.100 [BPC1](INFO): Received data from all battery sticks. 2023-04-14T23:33:03.864Z,1681515183.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:33:18.692Z,1681515198.692 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-14T23:33:18.692Z,1681515198.692 [DVL_micro](ERROR): Failed to parse: :TS,000000000000+20.9,0000.0,1489.0,000 2023-04-14T23:33:34.993Z,1681515214.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:33:44.175Z,1681515224.175 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-14T23:28:43.0Z 2023-04-14T23:33:44.175Z,1681515224.175 [Default:CheckIn:Read_GPS] Stopped 2023-04-14T23:33:44.175Z,1681515224.175 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-14T23:33:44.580Z,1681515224.580 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-04-14T23:33:51.426Z,1681515231.426 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-04-14T23:33:51.426Z,1681515231.426 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-04-14T23:33:51.437Z,1681515231.437 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-04-14T23:33:51.833Z,1681515231.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-04-14T23:33:51.833Z,1681515231.833 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-04-14T23:34:06.117Z,1681515246.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:34:37.240Z,1681515277.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:35:02.521Z,1681515302.521 [DVL_micro](ERROR): Failed to parse::R99,+9999.999,+9999.99 2023-04-14T23:35:08.376Z,1681515308.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:35:11.389Z,1681515311.389 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735596 2023-04-14T23:35:30.400Z,1681515330.400 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-04-14T23:35:30.400Z,1681515330.400 [NAL9602] Data Fault, FailCount= 1 2023-04-14T23:35:30.400Z,1681515330.400 [NAL9602](ERROR): Data Fault 2023-04-14T23:35:30.461Z,1681515330.461 [CBIT](ERROR): Data Fault in component: NAL9602 2023-04-14T23:35:30.791Z,1681515330.791 [NAL9602](INFO): Powering down 2023-04-14T23:35:31.628Z,1681515331.628 [CBIT](INFO): Clearing failed state for component NAL9602 2023-04-14T23:35:31.628Z,1681515331.628 [NAL9602] No Fault, FailCount= 1 2023-04-14T23:35:42.512Z,1681515342.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:36:01.094Z,1681515361.094 [NAL9602](INFO): Powering up NAL9602 2023-04-14T23:36:12.003Z,1681515372.003 [NAL9602](INFO): NAL9602 initialized 2023-04-14T23:36:13.636Z,1681515373.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:36:44.760Z,1681515404.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:36:52.408Z,1681515412.408 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-04-14T23:36:52.408Z,1681515412.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-04-14T23:36:52.439Z,1681515412.439 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-04-14T23:36:52.822Z,1681515412.822 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-04-14T23:36:52.822Z,1681515412.822 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-04-14T23:37:15.884Z,1681515435.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:37:47.008Z,1681515467.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:38:18.136Z,1681515498.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:38:27.352Z,1681515507.352 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-14T23:38:27.352Z,1681515507.352 [DVL_micro](ERROR): Failed to parse: :BI,+00044,-00090,-00459,+00BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-04-14T23:38:40.283Z,1681515520.283 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-14T23:38:40.283Z,1681515520.283 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+99999.99 2023-04-14T23:38:49.264Z,1681515529.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:39:20.389Z,1681515560.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:39:51.524Z,1681515591.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:39:53.412Z,1681515593.412 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-04-14T23:39:53.412Z,1681515593.412 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-04-14T23:39:53.422Z,1681515593.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-04-14T23:39:53.825Z,1681515593.825 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-04-14T23:39:53.825Z,1681515593.825 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-04-14T23:40:22.648Z,1681515622.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:40:53.784Z,1681515653.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:41:24.908Z,1681515684.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:41:38.088Z,1681515698.088 [NAL9602](INFO): SBD MO Status=2, MOMSN=2444, MT Status=2, MTMSN=0 2023-04-14T23:41:38.088Z,1681515698.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-14T23:41:56.060Z,1681515716.060 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:42:15.725Z,1681515735.725 [NAL9602](INFO): SBD MO Status=2, MOMSN=2444, MT Status=2, MTMSN=0 2023-04-14T23:42:15.725Z,1681515735.725 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-14T23:42:16.937Z,1681515736.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234216.00,A,3648.16574,N,12147.28156,W,0.039,337.92,140423,,,A*7B 2023-04-14T23:42:16.941Z,1681515736.941 [NAL9602](INFO): GPS fix at 20230414T234216: (36.802762, -121.788026) 2023-04-14T23:42:20.186Z,1681515740.186 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-14T23:42:20.186Z,1681515740.186 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+21.1,0000.0,1489.0,,-00088,+0423,+000I,-00088,+0423,+00000,I 2023-04-14T23:42:27.204Z,1681515747.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:42:32.793Z,1681515752.793 [NAL9602](INFO): SBD MO Status=2, MOMSN=2444, MT Status=2, MTMSN=0 2023-04-14T23:42:32.794Z,1681515752.794 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-14T23:42:58.328Z,1681515778.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:43:29.460Z,1681515809.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:44:00.592Z,1681515840.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:44:31.728Z,1681515871.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:45:02.856Z,1681515902.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:45:33.980Z,1681515933.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:46:00.429Z,1681515960.429 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-14T23:46:00.429Z,1681515960.429 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+21.1,00000,000 2023-04-14T23:46:05.124Z,1681515965.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:46:36.249Z,1681515996.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:47:07.384Z,1681516027.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:47:38.508Z,1681516058.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:47:46.682Z,1681516066.682 [DVL_micro](ERROR): Failed to parse: :WI,-00277,+01102,-00652,+00000,A 2023-04-14T23:48:09.636Z,1681516089.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-14T23:48:38.164Z,1681516118.164 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003128 2023-04-14T23:48:45.675Z,1681516125.675 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230414T232437/Courier0004.lzma 2023-04-14T23:48:46.677Z,1681516126.677 [DataOverHttps](INFO): Moved sent file to Logs/20230414T232437/Courier0004.lzma.bak 2023-04-14T23:48:46.678Z,1681516126.678 [DataOverHttps](INFO): SBD MOMSN=18222948 2023-04-14T23:49:02.636Z,1681516142.636 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20230414T232437/Express0005.lzma 2023-04-14T23:49:03.637Z,1681516143.637 [DataOverHttps](INFO): Moved sent file to Logs/20230414T232437/Express0005.lzma.bak 2023-04-14T23:49:03.638Z,1681516143.638 [DataOverHttps](INFO): SBD MOMSN=18222950 2023-04-14T23:49:04.669Z,1681516144.669 [Default:CheckIn:Read_Iridium] Stopped 2023-04-14T23:49:04.669Z,1681516144.669 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-14T23:49:04.669Z,1681516144.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-14T23:49:09.505Z,1681516149.505 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-14T23:49:35.523Z,1681516175.523 [DVL_micro](ERROR): Failed to parse: :WI,-00206,+00784,-00567,+00000,A 2023-04-14T23:49:36.134Z,1681516176.134 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter 2023-04-14T23:49:36.142Z,1681516176.142 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2023-04-14T23:49:36.330Z,1681516176.330 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-04-14T23:49:43.423Z,1681516183.423 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position 2023-04-14T23:49:43.630Z,1681516183.630 [Reporter](INFO): platform_buoyancy_position 938.612671 cc 2023-04-14T23:49:44.057Z,1681516184.057 [Reporter](INFO): platform_buoyancy_position 934.986572 cc 2023-04-14T23:49:44.503Z,1681516184.503 [Reporter](INFO): platform_buoyancy_position 930.823242 cc 2023-04-14T23:49:44.886Z,1681516184.886 [Reporter](INFO): platform_buoyancy_position 926.391357 cc 2023-04-14T23:49:45.263Z,1681516185.263 [Reporter](INFO): platform_buoyancy_position 920.079224 cc 2023-04-14T23:49:45.662Z,1681516185.662 [Reporter](INFO): platform_buoyancy_position 915.647339 cc 2023-04-14T23:49:46.056Z,1681516186.056 [Reporter](INFO): platform_buoyancy_position 911.349731 cc 2023-04-14T23:49:46.410Z,1681516186.410 [Reporter](INFO): platform_buoyancy_position 907.186462 cc 2023-04-14T23:49:46.818Z,1681516186.818 [Reporter](INFO): platform_buoyancy_position 900.740051 cc 2023-04-14T23:49:47.234Z,1681516187.234 [Reporter](INFO): platform_buoyancy_position 895.636658 cc 2023-04-14T23:49:47.609Z,1681516187.609 [Reporter](INFO): platform_buoyancy_position 889.996033 cc 2023-04-14T23:49:48.026Z,1681516188.026 [Reporter](INFO): platform_buoyancy_position 885.698425 cc 2023-04-14T23:49:48.442Z,1681516188.442 [Reporter](INFO): platform_buoyancy_position 879.117737 cc 2023-04-14T23:49:48.814Z,1681516188.814 [Reporter](INFO): platform_buoyancy_position 874.820129 cc 2023-04-14T23:49:49.226Z,1681516189.226 [Reporter](INFO): platform_buoyancy_position 870.522522 cc 2023-04-14T23:49:49.642Z,1681516189.642 [Reporter](INFO): platform_buoyancy_position 864.344727 cc 2023-04-14T23:49:50.038Z,1681516190.038 [Reporter](INFO): platform_buoyancy_position 859.644226 cc 2023-04-14T23:49:50.427Z,1681516190.427 [Reporter](INFO): platform_buoyancy_position 853.466431 cc 2023-04-14T23:49:50.850Z,1681516190.850 [Reporter](INFO): platform_buoyancy_position 849.034546 cc 2023-04-14T23:49:51.262Z,1681516191.262 [Reporter](INFO): platform_buoyancy_position 842.722473 cc 2023-04-14T23:49:51.654Z,1681516191.654 [Reporter](INFO): platform_buoyancy_position 838.290527 cc 2023-04-14T23:49:52.054Z,1681516192.054 [Reporter](INFO): platform_buoyancy_position 831.978455 cc 2023-04-14T23:49:52.470Z,1681516192.470 [Reporter](INFO): platform_buoyancy_position 827.546570 cc 2023-04-14T23:49:52.874Z,1681516192.874 [Reporter](INFO): platform_buoyancy_position 821.368713 cc 2023-04-14T23:49:53.298Z,1681516193.298 [Reporter](INFO): platform_buoyancy_position 816.936829 cc 2023-04-14T23:49:53.662Z,1681516193.662 [Reporter](INFO): platform_buoyancy_position 810.759033 cc 2023-04-14T23:49:54.078Z,1681516194.078 [Reporter](INFO): platform_buoyancy_position 806.192810 cc 2023-04-14T23:49:54.486Z,1681516194.486 [Reporter](INFO): platform_buoyancy_position 800.955139 cc 2023-04-14T23:49:54.886Z,1681516194.886 [Reporter](INFO): platform_buoyancy_position 795.448853 cc 2023-04-14T23:49:55.285Z,1681516195.285 [Reporter](INFO): platform_buoyancy_position 790.748352 cc 2023-04-14T23:49:55.694Z,1681516195.694 [Reporter](INFO): platform_buoyancy_position 784.570557 cc 2023-04-14T23:49:56.117Z,1681516196.117 [Reporter](INFO): platform_buoyancy_position 779.870056 cc 2023-04-14T23:49:56.506Z,1681516196.506 [Reporter](INFO): platform_buoyancy_position 773.826538 cc 2023-04-14T23:49:56.891Z,1681516196.891 [Reporter](INFO): platform_buoyancy_position 769.528931 cc 2023-04-14T23:49:57.306Z,1681516197.306 [Reporter](INFO): platform_buoyancy_position 763.351135 cc 2023-04-14T23:49:57.718Z,1681516197.718 [Reporter](INFO): platform_buoyancy_position 758.919250 cc 2023-04-14T23:49:58.123Z,1681516198.123 [Reporter](INFO): platform_buoyancy_position 752.472839 cc 2023-04-14T23:49:58.539Z,1681516198.539 [Reporter](INFO): platform_buoyancy_position 748.040955 cc 2023-04-14T23:49:58.918Z,1681516198.918 [Reporter](INFO): platform_buoyancy_position 742.937561 cc 2023-04-14T23:49:59.334Z,1681516199.334 [Reporter](INFO): platform_buoyancy_position 737.296936 cc 2023-04-14T23:49:59.735Z,1681516199.735 [Reporter](INFO): platform_buoyancy_position 730.984863 cc 2023-04-14T23:50:00.151Z,1681516200.151 [Reporter](INFO): platform_buoyancy_position 726.687256 cc 2023-04-14T23:50:00.577Z,1681516200.577 [Reporter](INFO): platform_buoyancy_position 720.106506 cc 2023-04-14T23:50:00.958Z,1681516200.958 [Reporter](INFO): platform_buoyancy_position 715.808960 cc 2023-04-14T23:50:01.350Z,1681516201.350 [Reporter](INFO): platform_buoyancy_position 709.362549 cc 2023-04-14T23:50:01.738Z,1681516201.738 [Reporter](INFO): platform_buoyancy_position 705.199219 cc 2023-04-14T23:50:02.158Z,1681516202.158 [Reporter](INFO): platform_buoyancy_position 700.767334 cc 2023-04-14T23:50:02.569Z,1681516202.569 [Reporter](INFO): platform_buoyancy_position 694.455261 cc 2023-04-14T23:50:02.978Z,1681516202.978 [Reporter](INFO): platform_buoyancy_position 690.023315 cc 2023-04-14T23:50:03.359Z,1681516203.359 [Reporter](INFO): platform_buoyancy_position 683.845520 cc 2023-04-14T23:50:03.775Z,1681516203.775 [Reporter](INFO): platform_buoyancy_position 679.279358 cc 2023-04-14T23:50:04.186Z,1681516204.186 [Reporter](INFO): platform_buoyancy_position 673.101501 cc 2023-04-14T23:50:04.598Z,1681516204.598 [Reporter](INFO): platform_buoyancy_position 668.535339 cc 2023-04-14T23:50:04.974Z,1681516204.974 [Reporter](INFO): platform_buoyancy_position 663.566223 cc 2023-04-14T23:50:05.394Z,1681516205.394 [Reporter](INFO): platform_buoyancy_position 657.925659 cc 2023-04-14T23:50:05.805Z,1681516205.805 [Reporter](INFO): platform_buoyancy_position 652.285034 cc 2023-04-14T23:50:06.225Z,1681516206.225 [Reporter](INFO): platform_buoyancy_position 647.047302 cc 2023-04-14T23:50:06.593Z,1681516206.593 [Reporter](INFO): platform_buoyancy_position 642.212524 cc 2023-04-14T23:50:07.006Z,1681516207.006 [Reporter](INFO): platform_buoyancy_position 636.437622 cc 2023-04-14T23:50:07.420Z,1681516207.420 [Reporter](INFO): platform_buoyancy_position 631.602844 cc 2023-04-14T23:50:07.814Z,1681516207.814 [Reporter](INFO): platform_buoyancy_position 625.559326 cc 2023-04-14T23:50:08.243Z,1681516208.243 [Reporter](INFO): platform_buoyancy_position 620.858826 cc 2023-04-14T23:50:08.676Z,1681516208.676 [Reporter](INFO): platform_buoyancy_position 614.815308 cc 2023-04-14T23:50:09.045Z,1681516209.045 [Reporter](INFO): platform_buoyancy_position 610.652039 cc 2023-04-14T23:50:09.422Z,1681516209.422 [Reporter](INFO): platform_buoyancy_position 604.071350 cc 2023-04-14T23:50:09.843Z,1681516209.843 [Reporter](INFO): platform_buoyancy_position 599.639404 cc 2023-04-14T23:50:10.254Z,1681516210.254 [Reporter](INFO): platform_buoyancy_position 593.461609 cc 2023-04-14T23:50:10.660Z,1681516210.660 [Reporter](INFO): platform_buoyancy_position 589.029724 cc 2023-04-14T23:50:11.085Z,1681516211.085 [Reporter](INFO): platform_buoyancy_position 582.583313 cc 2023-04-14T23:50:11.491Z,1681516211.491 [Reporter](INFO): platform_buoyancy_position 578.285706 cc 2023-04-14T23:50:11.867Z,1681516211.867 [Reporter](INFO): platform_buoyancy_position 572.107910 cc 2023-04-14T23:50:12.262Z,1681516212.262 [Reporter](INFO): platform_buoyancy_position 567.541748 cc 2023-04-14T23:50:12.655Z,1681516212.655 [Reporter](INFO): platform_buoyancy_position 563.109802 cc 2023-04-14T23:50:13.070Z,1681516213.070 [Reporter](INFO): platform_buoyancy_position 557.066345 cc 2023-04-14T23:50:13.488Z,1681516213.488 [Reporter](INFO): platform_buoyancy_position 552.500122 cc 2023-04-14T23:50:13.894Z,1681516213.894 [Reporter](INFO): platform_buoyancy_position 546.053711 cc 2023-04-14T23:50:14.274Z,1681516214.274 [Reporter](INFO): platform_buoyancy_position 541.487549 cc 2023-04-14T23:50:14.691Z,1681516214.691 [Reporter](INFO): platform_buoyancy_position 535.444031 cc 2023-04-14T23:50:15.098Z,1681516215.098 [Reporter](INFO): platform_buoyancy_position 531.012146 cc 2023-04-14T23:50:15.527Z,1681516215.527 [Reporter](INFO): platform_buoyancy_position 524.700012 cc 2023-04-14T23:50:15.882Z,1681516215.882 [Reporter](INFO): platform_buoyancy_position 520.133789 cc 2023-04-14T23:50:16.354Z,1681516216.354 [Reporter](INFO): platform_buoyancy_position 513.821716 cc 2023-04-14T23:50:16.725Z,1681516216.725 [Reporter](INFO): platform_buoyancy_position 509.389862 cc 2023-04-14T23:50:17.127Z,1681516217.127 [Reporter](INFO): platform_buoyancy_position 503.077759 cc 2023-04-14T23:50:17.514Z,1681516217.514 [Reporter](INFO): platform_buoyancy_position 498.780121 cc 2023-04-14T23:50:17.922Z,1681516217.922 [Reporter](INFO): platform_buoyancy_position 492.736633 cc 2023-04-14T23:50:18.338Z,1681516218.338 [Reporter](INFO): platform_buoyancy_position 488.170441 cc 2023-04-14T23:50:18.769Z,1681516218.769 [Reporter](INFO): platform_buoyancy_position 481.724030 cc 2023-04-14T23:50:19.137Z,1681516219.137 [Reporter](INFO): platform_buoyancy_position 477.292145 cc 2023-04-14T23:50:19.556Z,1681516219.556 [Reporter](INFO): platform_buoyancy_position 471.114349 cc 2023-04-14T23:50:19.938Z,1681516219.938 [Reporter](INFO): platform_buoyancy_position 466.548126 cc 2023-04-14T23:50:20.354Z,1681516220.354 [Reporter](INFO): platform_buoyancy_position 462.250519 cc 2023-04-14T23:50:20.766Z,1681516220.766 [Reporter](INFO): platform_buoyancy_position 456.207062 cc 2023-04-14T23:50:21.194Z,1681516221.194 [Reporter](INFO): platform_buoyancy_position 451.506561 cc 2023-04-14T23:50:21.567Z,1681516221.567 [Reporter](INFO): platform_buoyancy_position 447.208954 cc 2023-04-14T23:50:21.990Z,1681516221.990 [Reporter](INFO): platform_buoyancy_position 440.762543 cc 2023-04-14T23:50:22.390Z,1681516222.390 [Reporter](INFO): platform_buoyancy_position 434.450470 cc 2023-04-14T23:50:22.784Z,1681516222.784 [Reporter](INFO): platform_buoyancy_position 430.018524 cc 2023-04-14T23:50:23.170Z,1681516223.170 [Reporter](INFO): platform_buoyancy_position 423.706451 cc 2023-04-14T23:50:23.598Z,1681516223.598 [Reporter](INFO): platform_buoyancy_position 419.274567 cc 2023-04-14T23:50:24.059Z,1681516224.059 [Reporter](INFO): platform_buoyancy_position 412.828156 cc 2023-04-14T23:50:24.401Z,1681516224.401 [Reporter](INFO): platform_buoyancy_position 408.530548 cc 2023-04-14T23:50:24.806Z,1681516224.806 [Reporter](INFO): platform_buoyancy_position 402.218445 cc 2023-04-14T23:50:25.202Z,1681516225.202 [Reporter](INFO): platform_buoyancy_position 397.786530 cc 2023-04-14T23:50:25.630Z,1681516225.630 [Reporter](INFO): platform_buoyancy_position 391.474426 cc 2023-04-14T23:50:25.990Z,1681516225.990 [Reporter](INFO): platform_buoyancy_position 387.176849 cc 2023-04-14T23:50:26.406Z,1681516226.406 [Reporter](INFO): platform_buoyancy_position 381.670532 cc 2023-04-14T23:50:26.803Z,1681516226.803 [Reporter](INFO): platform_buoyancy_position 376.432831 cc 2023-04-14T23:50:27.198Z,1681516227.198 [Reporter](INFO): platform_buoyancy_position 372.000946 cc 2023-04-14T23:50:27.607Z,1681516227.607 [Reporter](INFO): platform_buoyancy_position 365.688843 cc 2023-04-14T23:50:28.077Z,1681516228.077 [Reporter](INFO): platform_buoyancy_position 359.108124 cc 2023-04-14T23:50:28.430Z,1681516228.430 [Reporter](INFO): platform_buoyancy_position 354.944824 cc 2023-04-14T23:50:28.839Z,1681516228.839 [Reporter](INFO): platform_buoyancy_position 350.378632 cc 2023-04-14T23:50:29.218Z,1681516229.218 [Reporter](INFO): platform_buoyancy_position 344.872345 cc 2023-04-14T23:50:29.634Z,1681516229.634 [Reporter](INFO): platform_buoyancy_position 339.634644 cc 2023-04-14T23:50:30.043Z,1681516230.043 [Reporter](INFO): platform_buoyancy_position 333.322540 cc 2023-04-14T23:50:30.442Z,1681516230.442 [Reporter](INFO): platform_buoyancy_position 329.024933 cc 2023-04-14T23:50:30.834Z,1681516230.834 [Reporter](INFO): platform_buoyancy_position 324.593048 cc 2023-04-14T23:50:31.258Z,1681516231.258 [Reporter](INFO): platform_buoyancy_position 318.280945 cc 2023-04-14T23:50:31.658Z,1681516231.658 [Reporter](INFO): platform_buoyancy_position 313.849030 cc 2023-04-14T23:50:32.058Z,1681516232.058 [Reporter](INFO): platform_buoyancy_position 307.671234 cc 2023-04-14T23:50:32.446Z,1681516232.446 [Reporter](INFO): platform_buoyancy_position 303.239319 cc 2023-04-14T23:50:32.870Z,1681516232.870 [Reporter](INFO): platform_buoyancy_position 297.330139 cc 2023-04-14T23:50:33.266Z,1681516233.266 [Reporter](INFO): platform_buoyancy_position 292.495331 cc 2023-04-14T23:50:33.662Z,1681516233.662 [Reporter](INFO): platform_buoyancy_position 286.451843 cc 2023-04-14T23:50:34.074Z,1681516234.074 [Reporter](INFO): platform_buoyancy_position 281.617035 cc 2023-04-14T23:50:34.486Z,1681516234.486 [Reporter](INFO): platform_buoyancy_position 275.439240 cc 2023-04-14T23:50:34.887Z,1681516234.887 [Reporter](INFO): platform_buoyancy_position 271.007324 cc 2023-04-14T23:50:35.271Z,1681516235.271 [Reporter](INFO): platform_buoyancy_position 266.172516 cc 2023-04-14T23:50:35.686Z,1681516235.686 [Reporter](INFO): platform_buoyancy_position 260.397644 cc 2023-04-14T23:50:36.102Z,1681516236.102 [Reporter](INFO): platform_buoyancy_position 255.831436 cc 2023-04-14T23:50:36.522Z,1681516236.522 [Reporter](INFO): platform_buoyancy_position 249.519302 cc 2023-04-14T23:50:36.891Z,1681516236.891 [Reporter](INFO): platform_buoyancy_position 245.356033 cc 2023-04-14T23:50:37.306Z,1681516237.306 [Reporter](INFO): platform_buoyancy_position 238.909637 cc 2023-04-14T23:50:37.718Z,1681516237.718 [Reporter](INFO): platform_buoyancy_position 234.477722 cc 2023-04-14T23:50:38.119Z,1681516238.119 [Reporter](INFO): platform_buoyancy_position 228.165619 cc 2023-04-14T23:50:38.506Z,1681516238.506 [Reporter](INFO): platform_buoyancy_position 223.465118 cc 2023-04-14T23:50:38.978Z,1681516238.978 [Reporter](INFO): platform_buoyancy_position 217.421631 cc 2023-04-14T23:50:39.342Z,1681516239.342 [Reporter](INFO): platform_buoyancy_position 212.989716 cc 2023-04-14T23:50:39.786Z,1681516239.786 [Reporter](INFO): platform_buoyancy_position 206.677612 cc 2023-04-14T23:52:32.616Z,1681516352.616 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter 2023-04-14T23:52:34.886Z,1681516354.886 [Reporter](INFO): platform_buoyancy_position 200.231232 cc 2023-04-14T23:52:35.718Z,1681516355.718 [Reporter](INFO): platform_buoyancy_position 197.948120 cc 2023-04-14T23:52:36.078Z,1681516356.078 [Reporter](INFO): platform_buoyancy_position 193.516235 cc 2023-04-14T23:52:36.498Z,1681516356.498 [Reporter](INFO): platform_buoyancy_position 187.338440 cc 2023-04-14T23:52:36.890Z,1681516356.890 [Reporter](INFO): platform_buoyancy_position 184.249512 cc 2023-04-14T23:52:37.312Z,1681516357.312 [Reporter](INFO): platform_buoyancy_position 178.743225 cc 2023-04-14T23:52:37.699Z,1681516357.699 [Reporter](INFO): platform_buoyancy_position 173.908417 cc 2023-04-14T23:52:38.110Z,1681516358.110 [Reporter](INFO): platform_buoyancy_position 167.864929 cc 2023-04-14T23:52:38.525Z,1681516358.525 [Reporter](INFO): platform_buoyancy_position 163.701630 cc 2023-04-14T23:52:38.926Z,1681516358.926 [Reporter](INFO): platform_buoyancy_position 157.389526 cc 2023-04-14T23:52:39.349Z,1681516359.349 [Reporter](INFO): platform_buoyancy_position 152.823303 cc 2023-04-14T23:52:39.725Z,1681516359.725 [Reporter](INFO): platform_buoyancy_position 148.122803 cc 2023-04-14T23:52:40.130Z,1681516360.130 [Reporter](INFO): platform_buoyancy_position 142.079315 cc 2023-04-14T23:52:40.542Z,1681516360.542 [Reporter](INFO): platform_buoyancy_position 137.781708 cc 2023-04-14T23:52:40.918Z,1681516360.918 [Reporter](INFO): platform_buoyancy_position 131.469604 cc 2023-04-14T23:52:41.370Z,1681516361.370 [Reporter](INFO): platform_buoyancy_position 124.888901 cc 2023-04-14T23:52:41.761Z,1681516361.761 [Reporter](INFO): platform_buoyancy_position 120.591316 cc 2023-04-14T23:52:42.176Z,1681516362.176 [Reporter](INFO): platform_buoyancy_position 115.353615 cc 2023-04-14T23:52:42.610Z,1681516362.610 [Reporter](INFO): platform_buoyancy_position 109.981606 cc 2023-04-14T23:52:53.395Z,1681516373.395 [DVL_micro](ERROR): Failed to parse: :WI,+00030,+00201,-00008,+00000,A 2023-04-14T23:53:02.725Z,1681516382.725 [CommandExec](IMPORTANT): got command maintain clear 2023-04-14T23:53:03.115Z,1681516383.115 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,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-04-14T23:53:05.211Z,1681516385.211 [Reporter](INFO): platform_buoyancy_position 101.386406 cc 2023-04-14T23:53:05.967Z,1681516385.967 [Reporter](INFO): platform_buoyancy_position 101.789314 cc 2023-04-14T23:53:06.385Z,1681516386.385 [Reporter](INFO): platform_buoyancy_position 105.684013 cc 2023-04-14T23:53:06.801Z,1681516386.801 [Reporter](INFO): platform_buoyancy_position 109.847313 cc 2023-04-14T23:53:07.215Z,1681516387.215 [Reporter](INFO): platform_buoyancy_position 116.293709 cc 2023-04-14T23:53:07.586Z,1681516387.586 [Reporter](INFO): platform_buoyancy_position 120.725609 cc 2023-04-14T23:53:07.994Z,1681516387.994 [Reporter](INFO): platform_buoyancy_position 127.037712 cc 2023-04-14T23:53:08.415Z,1681516388.415 [Reporter](INFO): platform_buoyancy_position 131.335327 cc 2023-04-14T23:53:08.819Z,1681516388.819 [Reporter](INFO): platform_buoyancy_position 137.781708 cc 2023-04-14T23:53:09.235Z,1681516389.235 [Reporter](INFO): platform_buoyancy_position 142.213623 cc 2023-04-14T23:53:09.602Z,1681516389.602 [Reporter](INFO): platform_buoyancy_position 146.511200 cc 2023-04-14T23:53:09.705Z,1681516389.705 [CommandExec](IMPORTANT): got command report clear 2023-04-14T23:54:00.867Z,1681516440.867 [DVL_micro](ERROR): Failed to parse: :WI,+00012,-04810,+00133,+00000,A 2023-04-14T23:54:05.315Z,1681516445.315 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-14T23:54:05.315Z,1681516445.315 [Default:CheckIn:C.Wait] Stopped 2023-04-14T23:54:05.315Z,1681516445.315 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-14T23:54:05.315Z,1681516445.315 [Default:CheckIn:D] Running Loop=1 2023-04-14T23:54:05.755Z,1681516445.755 [Default:CheckIn:D] Stopped 2023-04-14T23:54:05.755Z,1681516445.755 [Default:CheckIn:E] Running Loop=1 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.591954 min 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn:E] Stopped 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn] Stopped 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn](INFO): Running loop #2 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn] Running Loop=2 2023-04-14T23:54:06.151Z,1681516446.151 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-14T23:54:06.152Z,1681516446.152 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-14T23:54:08.139Z,1681516448.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235407.00,A,3648.16672,N,12147.28160,W,0.292,309.89,140423,,,A*78 2023-04-14T23:54:08.141Z,1681516448.141 [NAL9602](INFO): GPS fix at 20230414T235407: (36.802779, -121.788027) 2023-04-14T23:54:08.151Z,1681516448.151 [Default:CheckIn:Read_GPS] Stopped 2023-04-14T23:54:08.152Z,1681516448.152 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-14T23:54:16.015Z,1681516456.015 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230414T232437/Courier0007.lzma 2023-04-14T23:54:17.022Z,1681516457.022 [DataOverHttps](INFO): Moved sent file to Logs/20230414T232437/Courier0007.lzma.bak 2023-04-14T23:54:17.022Z,1681516457.022 [DataOverHttps](INFO): SBD MOMSN=18222984 2023-04-14T23:54:25.733Z,1681516465.733 [CommandExec](IMPORTANT): got command ibit 2023-04-14T23:54:26.099Z,1681516466.099 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-04-14T23:54:26.099Z,1681516466.099 [IBIT](IMPORTANT): Beginning control surface checks. 2023-04-14T23:54:26.115Z,1681516466.115 [CBIT](IMPORTANT): Beginning ground fault scan 2023-04-14T23:54:36.136Z,1681516476.136 [DataOverHttps](INFO): Sending 466 bytes from file Logs/20230414T232437/Express0008.lzma 2023-04-14T23:54:36.899Z,1681516476.899 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.020177 CHAN A1 (24V): 0.018658 CHAN A2 (12V): -0.033702 CHAN A3 (5V): -0.018275 CHAN B0 (3.3V): -0.010696 CHAN B1 (3.15aV): -0.005669 CHAN B2 (3.15bV): -0.019983 CHAN B3 (GND): -0.015676 OPEN: 0.009564 Full Scale: +/- 1 mA 2023-04-14T23:54:37.138Z,1681516477.138 [DataOverHttps](INFO): Moved sent file to Logs/20230414T232437/Express0008.lzma.bak 2023-04-14T23:54:37.138Z,1681516477.138 [DataOverHttps](INFO): SBD MOMSN=18222987 2023-04-14T23:55:02.191Z,1681516502.191 [CommandExec](IMPORTANT): got command get DAT.loadAtStartup 2023-04-14T23:55:02.192Z,1681516502.192 [CommandExec](IMPORTANT): DAT.loadAtStartup 1 bool 2023-04-14T23:55:07.085Z,1681516507.085 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadB3 2023-04-14T23:55:09.201Z,1681516509.201 [CommandExec](IMPORTANT): got command gfscan 2023-04-14T23:55:09.291Z,1681516509.291 [CBIT](IMPORTANT): Beginning ground fault scan 2023-04-14T23:55:20.136Z,1681516520.136 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.030498 CHAN A1 (24V): 0.020842 CHAN A2 (12V): -0.035292 CHAN A3 (5V): -0.019399 CHAN B0 (3.3V): -0.010433 CHAN B1 (3.15aV): -0.006105 CHAN B2 (3.15bV): -0.002447 CHAN B3 (GND): -0.015933 OPEN: 0.009258 Full Scale: +/- 1 mA 2023-04-14T23:55:27.055Z,1681516527.055 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802780 Longitude: -121.788025 2023-04-14T23:55:27.416Z,1681516527.416 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.532000 2023-04-14T23:55:27.417Z,1681516527.417 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2023-04-14T23:55:27.417Z,1681516527.417 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2023-04-14T23:55:27.815Z,1681516527.815 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-04-14T23:55:27.816Z,1681516527.816 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-04-14T23:55:27.816Z,1681516527.816 [IBIT](IMPORTANT): Pressure:9.529622 PSI 2023-04-14T23:55:27.817Z,1681516527.817 [IBIT](IMPORTANT): Humidity:3.796850 % 2023-04-14T23:55:28.201Z,1681516528.201 [IBIT](IMPORTANT): Vehicle Pitch:0.625268 degrees 2023-04-14T23:55:28.201Z,1681516528.201 [IBIT](IMPORTANT): Vehicle Roll:2.483474 degrees 2023-04-14T23:55:28.201Z,1681516528.201 [IBIT](IMPORTANT): Vehicle Heading:300.125702 degrees 2023-04-14T23:55:28.602Z,1681516528.602 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-04-14T23:55:28.602Z,1681516528.602 [IBIT](IMPORTANT): buoyancyNeutral: 79.764107 cc 2023-04-14T23:55:28.602Z,1681516528.602 [IBIT](IMPORTANT): massDefault: -1.483047 cm 2023-04-14T23:55:28.603Z,1681516528.603 [IBIT](IMPORTANT): stopDepth: 50.000000 m 2023-04-14T23:55:28.603Z,1681516528.603 [IBIT](IMPORTANT): abortDepth: 250.000000 m 2023-04-14T23:55:28.603Z,1681516528.603 [IBIT](IMPORTANT): IBIT PASSED 2023-04-14T23:55:29.007Z,1681516529.007 [Default:CheckIn:Read_Iridium] Stopped 2023-04-14T23:55:29.007Z,1681516529.007 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-14T23:55:29.008Z,1681516529.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-14T23:55:43.590Z,1681516543.590 [NAL9602](INFO): SBD MO Status=1, MOMSN=2444, MT Status=0, MTMSN=0 2023-04-14T23:55:43.591Z,1681516543.591 [NAL9602](INFO): No messages in MT queue 2023-04-14T23:55:54.508Z,1681516554.508 [CommandExec](IMPORTANT): got command configSet CBIT.stopDepth 60 meter persist 2023-04-14T23:56:14.288Z,1681516574.288 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-14T23:56:28.962Z,1681516588.962 [CommandExec](IMPORTANT): got command get DVL_micro.loadAtStartup 2023-04-14T23:56:28.962Z,1681516588.962 [CommandExec](IMPORTANT): DVL_micro.loadAtStartup 1 bool 2023-04-14T23:56:34.781Z,1681516594.781 [CommandExec](IMPORTANT): got command get 2023-04-14T23:56:34.781Z,1681516594.781 [CommandExec](FAULT): Incomplete syntax. Try: help get 2023-04-14T23:56:39.402Z,1681516599.402 [CommandExec](IMPORTANT): got command get DVL_micro.loadControl 2023-04-14T23:56:39.402Z,1681516599.402 [CommandExec](IMPORTANT): DVL_micro.loadControl /dev/loadB4 2023-04-14T23:56:41.093Z,1681516601.093 [DataOverHttps](IMPORTANT): SBD MTMSN=20230414T235640 2023-04-14T23:56:47.014Z,1681516607.014 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:47.015Z,1681516607.015 [DVL_micro] Hardware Fault, FailCount= 1 2023-04-14T23:56:47.015Z,1681516607.015 [DVL_micro](ERROR): Hardware Fault 2023-04-14T23:56:47.015Z,1681516607.015 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:47.092Z,1681516607.092 [DVL_micro](INFO): Powering down 2023-04-14T23:56:47.146Z,1681516607.146 [CBIT](ERROR): Hardware Fault in component: DVL_micro 2023-04-14T23:56:47.639Z,1681516607.639 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:47.639Z,1681516607.639 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:47.884Z,1681516607.884 [CBIT](INFO): Clearing failed state for component DVL_micro 2023-04-14T23:56:47.884Z,1681516607.884 [DVL_micro] No Fault, FailCount= 1 2023-04-14T23:56:47.951Z,1681516607.951 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:47.951Z,1681516607.951 [DVL_micro] Hardware Fault, FailCount= 2 2023-04-14T23:56:47.951Z,1681516607.951 [DVL_micro](ERROR): Hardware Fault 2023-04-14T23:56:47.951Z,1681516607.951 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:48.159Z,1681516608.159 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:48.159Z,1681516608.159 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:48.244Z,1681516608.244 [CBIT](ERROR): Hardware Fault in component: DVL_micro 2023-04-14T23:56:48.244Z,1681516608.244 [CBIT](INFO): Clearing failed state for component DVL_micro 2023-04-14T23:56:48.244Z,1681516608.244 [DVL_micro] No Fault, FailCount= 2 2023-04-14T23:56:48.367Z,1681516608.367 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:48.367Z,1681516608.367 [DVL_micro] Hardware Fault, FailCount= 3 2023-04-14T23:56:48.367Z,1681516608.367 [DVL_micro](ERROR): Hardware Fault 2023-04-14T23:56:48.367Z,1681516608.367 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:48.574Z,1681516608.574 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:48.575Z,1681516608.575 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:48.661Z,1681516608.661 [CBIT](ERROR): Hardware Fault in component: DVL_micro 2023-04-14T23:56:48.662Z,1681516608.662 [CBIT](CRITICAL): Hardware Fault in component: DVL_micro 2023-04-14T23:56:48.784Z,1681516608.784 [DataOverHttps](INFO): Received command: ibit 2023-04-14T23:56:48.788Z,1681516608.788 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:48.789Z,1681516608.789 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:48.789Z,1681516608.789 [CommandExec](IMPORTANT): got command ibit 2023-04-14T23:56:48.992Z,1681516608.992 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:48.993Z,1681516608.993 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:49.079Z,1681516609.079 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-04-14T23:56:49.079Z,1681516609.079 [IBIT](IMPORTANT): Beginning control surface checks. 2023-04-14T23:56:49.080Z,1681516609.080 [CBIT](INFO): Critical error at 20230414T235648 2023-04-14T23:56:49.083Z,1681516609.083 [CBIT](IMPORTANT): Beginning ground fault scan 2023-04-14T23:56:49.199Z,1681516609.199 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:49.199Z,1681516609.199 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:49.407Z,1681516609.407 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:49.407Z,1681516609.407 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:49.615Z,1681516609.615 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:49.615Z,1681516609.615 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:49.834Z,1681516609.834 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:49.835Z,1681516609.835 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:50.031Z,1681516610.031 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:50.031Z,1681516610.031 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:50.243Z,1681516610.243 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:50.244Z,1681516610.244 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:50.450Z,1681516610.450 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:50.451Z,1681516610.451 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:50.659Z,1681516610.659 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:50.660Z,1681516610.660 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:50.663Z,1681516610.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235650.00,A,3648.17131,N,12147.27258,W,2.294,112.86,140423,,,A*7D 2023-04-14T23:56:50.666Z,1681516610.666 [NAL9602](INFO): GPS fix at 20230414T235650: (36.802855, -121.787876) 2023-04-14T23:56:50.891Z,1681516610.891 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:50.891Z,1681516610.891 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:51.074Z,1681516611.074 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:56:51.075Z,1681516611.075 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:56:52.104Z,1681516612.104 [CommandExec](IMPORTANT): got command failComponent 2023-04-14T23:56:52.104Z,1681516612.104 [CommandExec](IMPORTANT): Failed components: 2023-04-14T23:56:52.105Z,1681516612.105 [CommandExec](IMPORTANT): DVL_micro: Hardware Fault 2023-04-14T23:56:59.965Z,1681516619.965 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.025303 CHAN A1 (24V): 0.023602 CHAN A2 (12V): -0.017224 CHAN A3 (5V): -0.025070 CHAN B0 (3.3V): -0.014261 CHAN B1 (3.15aV): -0.007418 CHAN B2 (3.15bV): -0.004713 CHAN B3 (GND): -0.015936 OPEN: 0.008840 Full Scale: +/- 1 mA 2023-04-14T23:57:02.568Z,1681516622.568 [CommandExec](IMPORTANT): got command failComponent hardware DVL_micro 2023-04-14T23:57:02.568Z,1681516622.568 [CommandExec](IMPORTANT): DVL_micro failureMode is Hardware Fault 2023-04-14T23:57:11.210Z,1681516631.210 [CommandExec](IMPORTANT): got command get DVL_micro.loadControl 2023-04-14T23:57:11.210Z,1681516631.210 [CommandExec](IMPORTANT): DVL_micro.loadControl /dev/loadB4 2023-04-14T23:57:17.345Z,1681516637.345 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadB4 2023-04-14T23:57:19.124Z,1681516639.124 [CommandExec](IMPORTANT): got command gfscan 2023-04-14T23:57:19.349Z,1681516639.349 [CBIT](IMPORTANT): Beginning ground fault scan 2023-04-14T23:57:20.147Z,1681516640.147 [NAL9602](INFO): SBD MO Status=1, MOMSN=2445, MT Status=0, MTMSN=0 2023-04-14T23:57:20.147Z,1681516640.147 [NAL9602](INFO): No messages in MT queue 2023-04-14T23:57:21.351Z,1681516641.351 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235721.00,A,3648.15881,N,12147.25099,W,2.663,188.24,140423,,,A*70 2023-04-14T23:57:21.353Z,1681516641.353 [NAL9602](INFO): GPS fix at 20230414T235721: (36.802647, -121.787516) 2023-04-14T23:57:24.586Z,1681516644.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235724.00,A,3648.15683,N,12147.25189,W,2.838,199.41,140423,,,A*7A 2023-04-14T23:57:24.589Z,1681516644.589 [NAL9602](INFO): GPS fix at 20230414T235724: (36.802614, -121.787532) 2023-04-14T23:57:27.410Z,1681516647.410 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235727.00,A,3648.15446,N,12147.25269,W,3.013,199.08,140423,,,A*72 2023-04-14T23:57:27.413Z,1681516647.413 [NAL9602](INFO): GPS fix at 20230414T235727: (36.802574, -121.787545) 2023-04-14T23:57:30.639Z,1681516650.639 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235730.00,A,3648.15279,N,12147.25317,W,1.633,197.06,140423,,,A*70 2023-04-14T23:57:30.642Z,1681516650.642 [NAL9602](INFO): GPS fix at 20230414T235730: (36.802546, -121.787553) 2023-04-14T23:57:30.682Z,1681516650.682 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004952 CHAN A1 (24V): 0.004190 CHAN A2 (12V): -0.003921 CHAN A3 (5V): -0.002748 CHAN B0 (3.3V): -0.000733 CHAN B1 (3.15aV): -0.001340 CHAN B2 (3.15bV): -0.000807 CHAN B3 (GND): -0.000133 OPEN: 0.002510 Full Scale: +/- 1 mA 2023-04-14T23:57:33.470Z,1681516653.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235733.00,A,3648.15206,N,12147.25330,W,0.875,203.80,140423,,,A*73 2023-04-14T23:57:33.488Z,1681516653.488 [NAL9602](INFO): GPS fix at 20230414T235733: (36.802534, -121.787555) 2023-04-14T23:57:35.553Z,1681516655.553 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802536 Longitude: -121.787552 2023-04-14T23:57:35.983Z,1681516655.983 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.572001 2023-04-14T23:57:35.988Z,1681516655.988 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2023-04-14T23:57:35.988Z,1681516655.988 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2023-04-14T23:57:36.321Z,1681516656.321 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-04-14T23:57:36.322Z,1681516656.322 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-04-14T23:57:36.322Z,1681516656.322 [IBIT](IMPORTANT): Pressure:9.508591 PSI 2023-04-14T23:57:36.322Z,1681516656.322 [IBIT](IMPORTANT): Humidity:3.827371 % 2023-04-14T23:57:36.762Z,1681516656.762 [IBIT](IMPORTANT): Vehicle Pitch:0.080383 degrees 2023-04-14T23:57:36.763Z,1681516656.763 [IBIT](IMPORTANT): Vehicle Roll:3.129537 degrees 2023-04-14T23:57:36.763Z,1681516656.763 [IBIT](IMPORTANT): Vehicle Heading:189.278229 degrees 2023-04-14T23:57:37.129Z,1681516657.129 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-04-14T23:57:37.129Z,1681516657.129 [IBIT](IMPORTANT): buoyancyNeutral: 79.764107 cc 2023-04-14T23:57:37.130Z,1681516657.130 [IBIT](IMPORTANT): massDefault: -1.483047 cm 2023-04-14T23:57:37.130Z,1681516657.130 [IBIT](IMPORTANT): stopDepth: 60.000000 m 2023-04-14T23:57:37.130Z,1681516657.130 [IBIT](IMPORTANT): abortDepth: 250.000000 m 2023-04-14T23:57:37.130Z,1681516657.130 [IBIT](IMPORTANT): IBIT PASSED 2023-04-14T23:57:49.840Z,1681516669.840 [CommandExec](IMPORTANT): got command failComponent 2023-04-14T23:57:49.841Z,1681516669.841 [CommandExec](IMPORTANT): Failed components: 2023-04-14T23:57:49.841Z,1681516669.841 [CommandExec](IMPORTANT): DVL_micro: Hardware Fault 2023-04-14T23:57:53.295Z,1681516673.295 [CommandExec](IMPORTANT): got command failComponent none DVL_micro 2023-04-14T23:57:53.295Z,1681516673.295 [DVL_micro] No Fault, FailCount= 3 2023-04-14T23:57:53.295Z,1681516673.295 [CommandExec](IMPORTANT): DVL_micro failureMode is No Fault 2023-04-14T23:58:06.202Z,1681516686.202 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-14T23:58:16.324Z,1681516696.324 [DVL_micro](ERROR): only read 2 of 4 data items 2023-04-14T23:58:16.325Z,1681516696.325 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999..99,+9999.99 2023-04-14T23:58:51.729Z,1681516731.729 [DataOverHttps](IMPORTANT): SBD MTMSN=20230414T235851 2023-04-14T23:58:59.180Z,1681516739.180 [DataOverHttps](INFO): Received command: restart app 2023-04-14T23:58:59.193Z,1681516739.193 [CommandExec](IMPORTANT): got command restart application 2023-04-14T23:59:00.196Z,1681516740.196 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:00.196Z,1681516740.196 [CommandExec](INFO): Uninitializing the command executive. 2023-04-14T23:59:00.196Z,1681516740.196 [CommandExec](INFO): Uninitializing the command scheduler. 2023-04-14T23:59:00.196Z,1681516740.196 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.200Z,1681516740.200 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-04-14T23:59:00.200Z,1681516740.200 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-04-14T23:59:00.200Z,1681516740.200 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.201Z,1681516740.201 [NavChartDb](INFO): Join timeout helper Thread ID is 1399 2023-04-14T23:59:00.280Z,1681516740.280 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:00.280Z,1681516740.280 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.284Z,1681516740.284 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-04-14T23:59:00.284Z,1681516740.284 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.284Z,1681516740.284 [DAT](INFO): Join timeout helper Thread ID is 1400 2023-04-14T23:59:00.296Z,1681516740.296 [DAT](INFO): Powering down 2023-04-14T23:59:00.373Z,1681516740.373 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:00.373Z,1681516740.373 [DAT](INFO): Powering down 2023-04-14T23:59:00.374Z,1681516740.374 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.392Z,1681516740.392 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-04-14T23:59:00.392Z,1681516740.392 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.392Z,1681516740.392 [Radio_Surface](INFO): Join timeout helper Thread ID is 1401 2023-04-14T23:59:00.524Z,1681516740.524 [Radio_Surface](INFO): Powering down 2023-04-14T23:59:00.525Z,1681516740.525 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:00.525Z,1681516740.525 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.544Z,1681516740.544 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-04-14T23:59:00.544Z,1681516740.544 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.544Z,1681516740.544 [Onboard](INFO): Join timeout helper Thread ID is 1402 2023-04-14T23:59:00.972Z,1681516740.972 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:00.972Z,1681516740.972 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.992Z,1681516740.992 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-04-14T23:59:00.992Z,1681516740.992 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:00.992Z,1681516740.992 [DataOverHttps](INFO): Join timeout helper Thread ID is 1403 2023-04-14T23:59:01.184Z,1681516741.184 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:01.184Z,1681516741.184 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.197Z,1681516741.197 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-04-14T23:59:01.197Z,1681516741.197 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.197Z,1681516741.197 [BackseatComponent](INFO): Join timeout helper Thread ID is 1404 2023-04-14T23:59:01.328Z,1681516741.328 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:01.328Z,1681516741.328 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.344Z,1681516741.344 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-04-14T23:59:01.345Z,1681516741.345 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.345Z,1681516741.345 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1405 2023-04-14T23:59:01.436Z,1681516741.436 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:01.436Z,1681516741.436 [WetLabsBB2FL](INFO): Powering down 2023-04-14T23:59:01.437Z,1681516741.437 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.444Z,1681516741.444 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-04-14T23:59:01.445Z,1681516741.445 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.445Z,1681516741.445 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1406 2023-04-14T23:59:01.796Z,1681516741.796 [CTD_Seabird](INFO): Powering down 2023-04-14T23:59:01.808Z,1681516741.808 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:01.808Z,1681516741.808 [CTD_Seabird](INFO): Powering down 2023-04-14T23:59:01.820Z,1681516741.820 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.828Z,1681516741.828 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-04-14T23:59:01.829Z,1681516741.829 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.829Z,1681516741.829 [logger](INFO): Join timeout helper Thread ID is 1407 2023-04-14T23:59:01.840Z,1681516741.840 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:01.840Z,1681516741.840 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.848Z,1681516741.848 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-04-14T23:59:01.849Z,1681516741.849 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.849Z,1681516741.849 [CommandLine](INFO): Join timeout helper Thread ID is 1408 2023-04-14T23:59:01.880Z,1681516741.880 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:01.880Z,1681516741.880 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.888Z,1681516741.888 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-04-14T23:59:01.889Z,1681516741.889 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.889Z,1681516741.889 [CommandExec](INFO): Join timeout helper Thread ID is 1409 2023-04-14T23:59:01.890Z,1681516741.890 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-04-14T23:59:01.890Z,1681516741.890 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:01.891Z,1681516741.891 [controlThread](INFO): Join timeout helper Thread ID is 1410 2023-04-14T23:59:01.968Z,1681516741.968 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-14T23:59:01.968Z,1681516741.968 [controlThread](DEBUG): Uninitializing ControlThread 2023-04-14T23:59:01.970Z,1681516741.970 [AHRS_M2](INFO): Powering down 2023-04-14T23:59:02.112Z,1681516742.112 [DVL_micro](INFO): Powering down 2023-04-14T23:59:02.113Z,1681516742.113 [NAL9602](INFO): Powering down 2023-04-14T23:59:02.114Z,1681516742.114 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-04-14T23:59:02.115Z,1681516742.115 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-04-14T23:59:02.116Z,1681516742.116 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-04-14T23:59:02.117Z,1681516742.117 [MissionManager](INFO): Uninitializing Mission Default 2023-04-14T23:59:02.117Z,1681516742.117 [Default] Stopped 2023-04-14T23:59:02.117Z,1681516742.117 [Default](DEBUG): Aggregate::uninitialize Default 2023-04-14T23:59:02.117Z,1681516742.117 [Default:B.GoToSurface] Stopped 2023-04-14T23:59:02.117Z,1681516742.117 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-04-14T23:59:02.117Z,1681516742.117 [Default:CheckIn] Stopped 2023-04-14T23:59:02.117Z,1681516742.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-14T23:59:02.117Z,1681516742.117 [Default:CheckIn:C.Wait] Stopped 2023-04-14T23:59:02.117Z,1681516742.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-14T23:59:02.120Z,1681516742.120 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-04-14T23:59:02.120Z,1681516742.120 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-04-14T23:59:02.121Z,1681516742.121 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-04-14T23:59:02.121Z,1681516742.121 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-04-14T23:59:02.121Z,1681516742.121 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-04-14T23:59:02.121Z,1681516742.121 [BuoyancyServo](INFO): Powering down 2023-04-14T23:59:02.136Z,1681516742.136 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-04-14T23:59:02.136Z,1681516742.136 [ElevatorServo](INFO): Powering down 2023-04-14T23:59:02.137Z,1681516742.137 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-04-14T23:59:02.137Z,1681516742.137 [MassServo](INFO): Powering down 2023-04-14T23:59:02.138Z,1681516742.138 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-04-14T23:59:02.138Z,1681516742.138 [RudderServo](INFO): Powering down 2023-04-14T23:59:02.139Z,1681516742.139 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-04-14T23:59:02.139Z,1681516742.139 [ThrusterHE](INFO): Powering down 2023-04-14T23:59:02.140Z,1681516742.140 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-04-14T23:59:02.140Z,1681516742.140 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-04-14T23:59:02.140Z,1681516742.140 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-04-14T23:59:02.140Z,1681516742.140 [CBIT](DEBUG): Powering off loads. 2023-04-14T23:59:02.152Z,1681516742.152 [CBIT](DEBUG): Disabling WDT. 2023-04-14T23:59:02.164Z,1681516742.164 [CBIT](DEBUG): Opening all GF detection circuits. 2023-04-14T23:59:02.165Z,1681516742.165 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.201Z,1681516742.201 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.206Z,1681516742.206 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.237Z,1681516742.237 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.241Z,1681516742.241 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.243Z,1681516742.243 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.250Z,1681516742.250 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.294Z,1681516742.294 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.346Z,1681516742.346 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-14T23:59:02.417Z,1681516742.417 [logger ThreadHandler](INFO): Thread cancelled.