2024-08-19T20:49:27.505Z,1724100567.505 [Supervisor](DEBUG): Initializing supervisor. 2024-08-19T20:49:27.509Z,1724100567.509 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-08-19T20:49:27.510Z,1724100567.510 [SyncHandler](INFO): Protected caller Thread ID is 1193 2024-08-19T20:49:27.510Z,1724100567.510 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-08-19T20:49:27.511Z,1724100567.511 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-08-19T20:49:27.511Z,1724100567.511 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1194 2024-08-19T20:49:27.515Z,1724100567.515 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-08-19T20:49:27.532Z,1724100567.532 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-08-19T20:49:27.533Z,1724100567.533 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-08-19T20:49:27.533Z,1724100567.533 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1195 2024-08-19T20:49:27.538Z,1724100567.538 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-08-19T20:49:27.538Z,1724100567.538 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-08-19T20:49:27.539Z,1724100567.539 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1196 2024-08-19T20:49:27.541Z,1724100567.541 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-08-19T20:49:27.542Z,1724100567.542 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-08-19T20:49:27.542Z,1724100567.542 [logger ThreadHandler](INFO): Protected caller Thread ID is 1197 2024-08-19T20:49:27.546Z,1724100567.546 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-08-19T20:49:27.546Z,1724100567.546 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-08-19T20:49:27.548Z,1724100567.548 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-08-19T20:49:27.847Z,1724100567.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-08-19T20:49:27.848Z,1724100567.848 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-08-19T20:49:28.071Z,1724100568.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-08-19T20:49:28.072Z,1724100568.072 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-08-19T20:49:28.179Z,1724100568.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-08-19T20:49:28.179Z,1724100568.179 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-08-19T20:49:28.377Z,1724100568.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-08-19T20:49:28.378Z,1724100568.378 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-08-19T20:49:28.888Z,1724100568.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-08-19T20:49:28.889Z,1724100568.889 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-08-19T20:49:29.010Z,1724100569.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-08-19T20:49:29.011Z,1724100569.011 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-08-19T20:49:29.296Z,1724100569.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-08-19T20:49:29.297Z,1724100569.297 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-08-19T20:49:29.520Z,1724100569.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-08-19T20:49:29.521Z,1724100569.521 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-08-19T20:49:30.084Z,1724100570.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-08-19T20:49:30.084Z,1724100570.084 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-08-19T20:49:30.508Z,1724100570.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-08-19T20:49:30.509Z,1724100570.509 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-08-19T20:49:30.600Z,1724100570.600 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-08-19T20:49:30.983Z,1724100570.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-08-19T20:49:30.984Z,1724100570.984 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-08-19T20:49:31.222Z,1724100571.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-08-19T20:49:31.222Z,1724100571.222 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-08-19T20:49:32.137Z,1724100572.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-08-19T20:49:32.137Z,1724100572.137 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-08-19T20:49:32.508Z,1724100572.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-08-19T20:49:32.508Z,1724100572.508 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-08-19T20:49:32.603Z,1724100572.603 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/ 2024-08-19T20:49:32.604Z,1724100572.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg 2024-08-19T20:49:32.700Z,1724100572.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg 2024-08-19T20:49:32.818Z,1724100572.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg 2024-08-19T20:49:32.939Z,1724100572.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg 2024-08-19T20:49:33.058Z,1724100573.058 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg 2024-08-19T20:49:33.156Z,1724100573.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg 2024-08-19T20:49:33.255Z,1724100573.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg 2024-08-19T20:49:33.370Z,1724100573.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg 2024-08-19T20:49:33.513Z,1724100573.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg 2024-08-19T20:49:33.687Z,1724100573.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg 2024-08-19T20:49:33.842Z,1724100573.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg 2024-08-19T20:49:34.114Z,1724100574.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-08-19T20:49:34.116Z,1724100574.116 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/ 2024-08-19T20:49:34.116Z,1724100574.116 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-08-19T20:49:34.125Z,1724100574.125 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-08-19T20:49:34.448Z,1724100574.448 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-08-19T20:49:34.449Z,1724100574.449 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-08-19T20:49:34.931Z,1724100574.931 [AHRS_M2] Loaded 2024-08-19T20:49:34.932Z,1724100574.932 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-08-19T20:49:35.724Z,1724100575.724 [BPC1] Loaded 2024-08-19T20:49:35.724Z,1724100575.724 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-08-19T20:49:35.808Z,1724100575.808 [DATMMP] Loaded 2024-08-19T20:49:35.808Z,1724100575.808 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-08-19T20:49:35.810Z,1724100575.810 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408A94E0 2024-08-19T20:49:35.810Z,1724100575.810 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1279 2024-08-19T20:49:35.881Z,1724100575.881 [DataOverHttps] Loaded 2024-08-19T20:49:35.881Z,1724100575.881 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-08-19T20:49:35.882Z,1724100575.882 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D94E0 2024-08-19T20:49:35.883Z,1724100575.883 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1280 2024-08-19T20:49:35.905Z,1724100575.905 [Depth_Keller] Loaded 2024-08-19T20:49:35.905Z,1724100575.905 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-08-19T20:49:35.968Z,1724100575.968 [NAL9602] Loaded 2024-08-19T20:49:35.968Z,1724100575.968 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-08-19T20:49:35.997Z,1724100575.997 [Onboard] Loaded 2024-08-19T20:49:35.997Z,1724100575.997 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-08-19T20:49:35.998Z,1724100575.998 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409094E0 2024-08-19T20:49:35.998Z,1724100575.998 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1281 2024-08-19T20:49:36.012Z,1724100576.012 [Power24vConverter] Loaded 2024-08-19T20:49:36.012Z,1724100576.012 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-08-19T20:49:36.028Z,1724100576.028 [Radio_Surface] Loaded 2024-08-19T20:49:36.029Z,1724100576.029 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-08-19T20:49:36.030Z,1724100576.030 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409394E0 2024-08-19T20:49:36.030Z,1724100576.030 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1282 2024-08-19T20:49:36.045Z,1724100576.045 [Sonardyne_Nano] Loaded 2024-08-19T20:49:36.045Z,1724100576.045 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-08-19T20:49:36.046Z,1724100576.046 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-08-19T20:49:36.046Z,1724100576.046 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-08-19T20:49:36.150Z,1724100576.150 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-08-19T20:49:36.150Z,1724100576.150 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-08-19T20:49:36.200Z,1724100576.200 [VerticalControl](DEBUG): Construct VerticalControl. 2024-08-19T20:49:36.257Z,1724100576.257 [VerticalControl] Loaded 2024-08-19T20:49:36.258Z,1724100576.258 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-08-19T20:49:36.260Z,1724100576.260 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-08-19T20:49:36.307Z,1724100576.307 [HorizontalControl] Loaded 2024-08-19T20:49:36.307Z,1724100576.307 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-08-19T20:49:36.310Z,1724100576.310 [SpeedControl](DEBUG): Construct SpeedControl. 2024-08-19T20:49:36.312Z,1724100576.312 [SpeedControl] Loaded 2024-08-19T20:49:36.313Z,1724100576.313 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-08-19T20:49:36.315Z,1724100576.315 [LoopControl](DEBUG): Construct LoopControl. 2024-08-19T20:49:36.316Z,1724100576.316 [LoopControl] Loaded 2024-08-19T20:49:36.316Z,1724100576.316 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-08-19T20:49:36.316Z,1724100576.316 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-08-19T20:49:36.317Z,1724100576.317 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-08-19T20:49:36.327Z,1724100576.327 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-08-19T20:49:36.327Z,1724100576.327 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-08-19T20:49:36.366Z,1724100576.366 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-08-19T20:49:36.366Z,1724100576.366 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-08-19T20:49:36.475Z,1724100576.475 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-08-19T20:49:36.475Z,1724100576.475 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-08-19T20:49:36.516Z,1724100576.516 [DeadReckonUsingSpeedCalculator] Loaded 2024-08-19T20:49:36.516Z,1724100576.516 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-08-19T20:49:36.557Z,1724100576.557 [DeadReckonWithRespectToSeafloor] Loaded 2024-08-19T20:49:36.557Z,1724100576.557 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-08-19T20:49:36.571Z,1724100576.571 [NavChart] Loaded 2024-08-19T20:49:36.571Z,1724100576.571 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-08-19T20:49:36.576Z,1724100576.576 [UniversalFixResidualReporter] Loaded 2024-08-19T20:49:36.577Z,1724100576.577 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-08-19T20:49:36.577Z,1724100576.577 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-08-19T20:49:36.578Z,1724100576.578 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-08-19T20:49:36.710Z,1724100576.710 [SBIT](DEBUG): Construct Startup Built In Test. 2024-08-19T20:49:36.719Z,1724100576.719 [SBIT] Loaded 2024-08-19T20:49:36.719Z,1724100576.719 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-08-19T20:49:36.722Z,1724100576.722 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-08-19T20:49:36.734Z,1724100576.734 [IBIT] Loaded 2024-08-19T20:49:36.734Z,1724100576.734 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-08-19T20:49:36.740Z,1724100576.740 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-08-19T20:49:36.843Z,1724100576.843 [CBIT] Loaded 2024-08-19T20:49:36.843Z,1724100576.843 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-08-19T20:49:36.844Z,1724100576.844 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-08-19T20:49:36.844Z,1724100576.844 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-08-19T20:49:37.008Z,1724100577.008 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-08-19T20:49:37.008Z,1724100577.008 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-08-19T20:49:37.137Z,1724100577.137 [BuoyancyServo] Loaded 2024-08-19T20:49:37.137Z,1724100577.137 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-08-19T20:49:37.208Z,1724100577.208 [ElevatorServo] Loaded 2024-08-19T20:49:37.208Z,1724100577.208 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-08-19T20:49:37.229Z,1724100577.229 [MassServo] Loaded 2024-08-19T20:49:37.229Z,1724100577.229 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-08-19T20:49:37.249Z,1724100577.249 [RudderServo] Loaded 2024-08-19T20:49:37.249Z,1724100577.249 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-08-19T20:49:37.266Z,1724100577.266 [ThrusterHE] Loaded 2024-08-19T20:49:37.266Z,1724100577.266 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-08-19T20:49:37.266Z,1724100577.266 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-08-19T20:49:37.267Z,1724100577.267 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-08-19T20:49:37.438Z,1724100577.438 [CTD_Seabird] Loaded 2024-08-19T20:49:37.438Z,1724100577.438 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-08-19T20:49:37.439Z,1724100577.439 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0 2024-08-19T20:49:37.440Z,1724100577.440 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1283 2024-08-19T20:49:37.475Z,1724100577.475 [ESPComponent] Loaded 2024-08-19T20:49:37.475Z,1724100577.475 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-08-19T20:49:37.497Z,1724100577.497 [PAR_Licor] Loaded 2024-08-19T20:49:37.497Z,1724100577.497 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-08-19T20:49:37.540Z,1724100577.540 [WetLabsBB2FL] Loaded 2024-08-19T20:49:37.540Z,1724100577.540 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-08-19T20:49:37.541Z,1724100577.541 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0 2024-08-19T20:49:37.542Z,1724100577.542 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1284 2024-08-19T20:49:37.543Z,1724100577.543 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-08-19T20:49:37.543Z,1724100577.543 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-08-19T20:49:37.591Z,1724100577.591 [DepthRateCalculator] Loaded 2024-08-19T20:49:37.591Z,1724100577.591 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-08-19T20:49:37.596Z,1724100577.596 [PitchRateCalculator] Loaded 2024-08-19T20:49:37.596Z,1724100577.596 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-08-19T20:49:37.605Z,1724100577.605 [SpeedCalculator] Loaded 2024-08-19T20:49:37.606Z,1724100577.606 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-08-19T20:49:37.610Z,1724100577.610 [YawRateCalculator] Loaded 2024-08-19T20:49:37.610Z,1724100577.610 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-08-19T20:49:37.629Z,1724100577.629 [ElevatorOffsetCalculator] Loaded 2024-08-19T20:49:37.629Z,1724100577.629 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-08-19T20:49:37.630Z,1724100577.630 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-08-19T20:49:37.630Z,1724100577.630 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-08-19T20:49:37.696Z,1724100577.696 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-08-19T20:49:37.702Z,1724100577.702 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-08-19T20:49:37.705Z,1724100577.705 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-08-19T20:49:37.716Z,1724100577.716 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-08-19T20:49:37.717Z,1724100577.717 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0 2024-08-19T20:49:37.718Z,1724100577.718 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1285 2024-08-19T20:49:37.722Z,1724100577.722 [Supervisor](INFO): Main Thread ID is 744 2024-08-19T20:49:37.722Z,1724100577.722 [Supervisor](DEBUG): Running supervisor. 2024-08-19T20:49:37.723Z,1724100577.723 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1286 2024-08-19T20:49:37.723Z,1724100577.723 [CommandExec](INFO): Initializing the command executive. 2024-08-19T20:49:37.725Z,1724100577.725 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1287 2024-08-19T20:49:37.728Z,1724100577.728 [controlThread ThreadHandler](INFO): Handler Thread ID is 1288 2024-08-19T20:49:37.728Z,1724100577.728 [controlThread](DEBUG): Initializing ControlThread 2024-08-19T20:49:37.734Z,1724100577.734 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-08-19T20:49:37.735Z,1724100577.735 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-08-19T20:49:37.736Z,1724100577.736 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-08-19T20:49:37.736Z,1724100577.736 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-08-19T20:49:37.737Z,1724100577.737 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-08-19T20:49:37.738Z,1724100577.738 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-08-19T20:49:37.738Z,1724100577.738 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-08-19T20:49:37.739Z,1724100577.739 [SBIT](INFO): Initialize SBIT Component. 2024-08-19T20:49:37.739Z,1724100577.739 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60 2024-08-19T20:49:37.739Z,1724100577.739 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95 2024-08-19T20:49:37.740Z,1724100577.740 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-08-19T20:49:37.741Z,1724100577.741 [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 2024-08-19T20:49:37.742Z,1724100577.742 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2024-08-19T20:49:37.743Z,1724100577.743 [IBIT](INFO): Initialize IBIT Component. 2024-08-19T20:49:37.743Z,1724100577.743 [CBIT](DEBUG): Initialize CBIT Component. 2024-08-19T20:49:37.744Z,1724100577.744 [logger ThreadHandler](INFO): Handler Thread ID is 1289 2024-08-19T20:49:37.757Z,1724100577.757 [CBIT](DEBUG): Initialized mux pins. 2024-08-19T20:49:37.757Z,1724100577.757 [CBIT](DEBUG): Initializing the watchdog timer. 2024-08-19T20:49:37.762Z,1724100577.762 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1290 2024-08-19T20:49:37.762Z,1724100577.762 [DATMMP](INFO): Start 2024-08-19T20:49:37.762Z,1724100577.762 [DATMMP](INFO): Powering up 2024-08-19T20:49:37.763Z,1724100577.763 [DATMMP](DEBUG): Initializing DATMMP. 2024-08-19T20:49:37.766Z,1724100577.766 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1292 2024-08-19T20:49:37.768Z,1724100577.768 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-08-19T20:49:37.778Z,1724100577.778 [Onboard ThreadHandler](INFO): Handler Thread ID is 1293 2024-08-19T20:49:37.782Z,1724100577.782 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-08-19T20:49:37.783Z,1724100577.783 [CBIT](DEBUG): Initializing heartbeat. 2024-08-19T20:49:37.798Z,1724100577.798 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1294 2024-08-19T20:49:37.813Z,1724100577.813 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1295 2024-08-19T20:49:37.814Z,1724100577.814 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-08-19T20:49:37.818Z,1724100577.818 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1297 2024-08-19T20:49:37.822Z,1724100577.822 [WetLabsBB2FL](INFO): Powering up 2024-08-19T20:49:37.823Z,1724100577.823 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1299 2024-08-19T20:49:37.826Z,1724100577.826 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-08-19T20:49:37.827Z,1724100577.827 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-08-19T20:49:37.827Z,1724100577.827 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-08-19T20:49:37.827Z,1724100577.827 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-08-19T20:49:37.827Z,1724100577.827 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-08-19T20:49:37.827Z,1724100577.827 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-08-19T20:49:37.828Z,1724100577.828 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-08-19T20:49:37.828Z,1724100577.828 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-08-19T20:49:37.828Z,1724100577.828 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-08-19T20:49:37.828Z,1724100577.828 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-08-19T20:49:37.828Z,1724100577.828 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-08-19T20:49:37.828Z,1724100577.828 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-08-19T20:49:37.853Z,1724100577.853 [CBIT](DEBUG): Deactivating GF circuits. 2024-08-19T20:49:37.853Z,1724100577.853 [CBIT](DEBUG): Deactivating emergency mode. 2024-08-19T20:49:37.893Z,1724100577.893 [CBIT](DEBUG): Backplane powered. 2024-08-19T20:49:37.897Z,1724100577.897 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-08-19T20:49:37.898Z,1724100577.898 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-08-19T20:49:37.898Z,1724100577.898 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-08-19T20:49:37.899Z,1724100577.899 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-08-19T20:49:37.899Z,1724100577.899 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-08-19T20:49:37.900Z,1724100577.900 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-08-19T20:49:37.901Z,1724100577.901 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-08-19T20:49:37.901Z,1724100577.901 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-08-19T20:49:37.902Z,1724100577.902 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-08-19T20:49:37.962Z,1724100577.962 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-08-19T20:49:37.966Z,1724100577.966 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-19T20:49:37.990Z,1724100577.990 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-08-19T20:49:37.991Z,1724100577.991 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-08-19T20:49:37.991Z,1724100577.991 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-08-19T20:49:37.992Z,1724100577.992 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-08-19T20:49:38.013Z,1724100578.013 [DATMMP](INFO): Starting 2024-08-19T20:49:38.205Z,1724100578.205 [Radio_Surface](INFO): Powering up 2024-08-19T20:49:38.265Z,1724100578.265 [DATMMP](INFO): Starting 2024-08-19T20:49:38.279Z,1724100578.279 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-08-19T20:49:38.285Z,1724100578.285 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-08-19T20:49:38.286Z,1724100578.286 [Default:A.Wait](DEBUG): Construct Wait. 2024-08-19T20:49:38.288Z,1724100578.288 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-19T20:49:38.313Z,1724100578.313 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-08-19T20:49:38.319Z,1724100578.319 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-08-19T20:49:38.332Z,1724100578.332 [Default:E.Execute](DEBUG): Construct Execute. 2024-08-19T20:49:38.343Z,1724100578.343 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-08-19T20:49:38.350Z,1724100578.350 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-08-19T20:49:38.357Z,1724100578.357 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-08-19T20:49:38.412Z,1724100578.412 [Power24vConverter](INFO): Powering up. 2024-08-19T20:49:38.413Z,1724100578.413 [Sonardyne_Nano](INFO): Initializing. 2024-08-19T20:49:38.481Z,1724100578.481 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-08-19T20:49:38.489Z,1724100578.489 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-08-19T20:49:38.490Z,1724100578.490 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-08-19T20:49:38.497Z,1724100578.497 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-08-19T20:49:38.498Z,1724100578.498 [MassServo](DEBUG): Initializing EZServoServo. 2024-08-19T20:49:38.505Z,1724100578.505 [MassServo](DEBUG): Initializing MassServo. 2024-08-19T20:49:38.506Z,1724100578.506 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-19T20:49:38.513Z,1724100578.513 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-19T20:49:38.514Z,1724100578.514 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-08-19T20:49:38.517Z,1724100578.517 [DATMMP](INFO): Starting 2024-08-19T20:49:38.521Z,1724100578.521 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-08-19T20:49:38.769Z,1724100578.769 [DATMMP](INFO): Starting 2024-08-19T20:49:39.021Z,1724100579.021 [DATMMP](INFO): Starting 2024-08-19T20:49:39.273Z,1724100579.273 [DATMMP](INFO): Starting 2024-08-19T20:49:39.525Z,1724100579.525 [DATMMP](INFO): Starting 2024-08-19T20:49:39.777Z,1724100579.777 [DATMMP](INFO): Starting 2024-08-19T20:49:39.929Z,1724100579.929 [WetLabsBB2FL](INFO): Powering down 2024-08-19T20:49:40.029Z,1724100580.029 [DATMMP](INFO): Starting 2024-08-19T20:49:40.281Z,1724100580.281 [DATMMP](INFO): Starting 2024-08-19T20:49:40.379Z,1724100580.379 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-08-19T20:49:40.533Z,1724100580.533 [DATMMP](INFO): Starting 2024-08-19T20:49:40.785Z,1724100580.785 [DATMMP](INFO): Starting 2024-08-19T20:49:41.037Z,1724100581.037 [DATMMP](INFO): Starting 2024-08-19T20:49:41.290Z,1724100581.290 [DATMMP](INFO): Starting 2024-08-19T20:49:41.541Z,1724100581.541 [DATMMP](INFO): Starting 2024-08-19T20:49:41.665Z,1724100581.665 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409 2024-08-19T20:49:41.794Z,1724100581.794 [DATMMP](INFO): Starting 2024-08-19T20:49:42.045Z,1724100582.045 [DATMMP](INFO): Starting 2024-08-19T20:49:42.297Z,1724100582.297 [DATMMP](INFO): Starting 2024-08-19T20:49:42.549Z,1724100582.549 [DATMMP](INFO): Starting 2024-08-19T20:49:42.801Z,1724100582.801 [DATMMP](INFO): Starting 2024-08-19T20:49:43.054Z,1724100583.054 [DATMMP](INFO): Starting 2024-08-19T20:49:43.306Z,1724100583.306 [DATMMP](INFO): Starting 2024-08-19T20:49:43.557Z,1724100583.557 [DATMMP](INFO): Starting 2024-08-19T20:49:43.809Z,1724100583.809 [DATMMP](INFO): Starting 2024-08-19T20:49:44.061Z,1724100584.061 [DATMMP](INFO): Starting 2024-08-19T20:49:44.102Z,1724100584.102 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-08-19T20:49:44.313Z,1724100584.313 [DATMMP](INFO): Starting 2024-08-19T20:49:44.565Z,1724100584.565 [DATMMP](INFO): Starting 2024-08-19T20:49:44.818Z,1724100584.818 [DATMMP](INFO): Starting 2024-08-19T20:49:45.069Z,1724100585.069 [DATMMP](INFO): Starting 2024-08-19T20:49:45.321Z,1724100585.321 [DATMMP](INFO): Starting 2024-08-19T20:49:45.573Z,1724100585.573 [DATMMP](INFO): Starting 2024-08-19T20:49:45.825Z,1724100585.825 [DATMMP](INFO): Starting 2024-08-19T20:49:46.078Z,1724100586.078 [DATMMP](INFO): Starting 2024-08-19T20:49:46.329Z,1724100586.329 [DATMMP](INFO): Starting 2024-08-19T20:49:46.581Z,1724100586.581 [DATMMP](INFO): Starting 2024-08-19T20:49:46.833Z,1724100586.833 [DATMMP](INFO): Starting 2024-08-19T20:49:47.085Z,1724100587.085 [DATMMP](INFO): Starting 2024-08-19T20:49:47.337Z,1724100587.337 [DATMMP](INFO): Starting 2024-08-19T20:49:47.589Z,1724100587.589 [DATMMP](INFO): Starting 2024-08-19T20:49:47.841Z,1724100587.841 [DATMMP](INFO): Starting 2024-08-19T20:49:48.093Z,1724100588.093 [DATMMP](INFO): Starting 2024-08-19T20:49:48.345Z,1724100588.345 [DATMMP](INFO): Starting 2024-08-19T20:49:48.597Z,1724100588.597 [DATMMP](INFO): Starting 2024-08-19T20:49:48.849Z,1724100588.849 [DATMMP](INFO): Starting 2024-08-19T20:49:49.101Z,1724100589.101 [DATMMP](INFO): Starting 2024-08-19T20:49:49.353Z,1724100589.353 [DATMMP](INFO): Starting 2024-08-19T20:49:49.354Z,1724100589.354 [DATMMP](INFO): DAT read: 2024-08-19T20:49:49.355Z,1724100589.355 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-08-19T20:49:49.605Z,1724100589.605 [DATMMP](INFO): Starting 2024-08-19T20:49:49.857Z,1724100589.857 [DATMMP](INFO): Starting 2024-08-19T20:49:50.109Z,1724100590.109 [DATMMP](INFO): Starting 2024-08-19T20:49:50.361Z,1724100590.361 [DATMMP](INFO): Starting 2024-08-19T20:49:50.613Z,1724100590.613 [DATMMP](INFO): Starting 2024-08-19T20:49:50.866Z,1724100590.866 [DATMMP](INFO): Starting 2024-08-19T20:49:50.866Z,1724100590.866 [DATMMP](INFO): DAT read: MF Frequency Band 2024-08-19T20:49:50.867Z,1724100590.867 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-08-19T20:49:50.868Z,1724100590.868 [DATMMP](INFO): DAT read: Jul 19 2000 20:49:45 2024-08-19T20:49:51.117Z,1724100591.117 [DATMMP](INFO): Starting 2024-08-19T20:49:51.371Z,1724100591.371 [DATMMP](INFO): Starting 2024-08-19T20:49:51.621Z,1724100591.621 [DATMMP](INFO): Starting 2024-08-19T20:49:51.873Z,1724100591.873 [DATMMP](INFO): Starting 2024-08-19T20:49:51.874Z,1724100591.874 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-08-19T20:49:51.875Z,1724100591.875 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-08-19T20:49:51.876Z,1724100591.876 [DATMMP](INFO): commRate: 800 2024-08-19T20:49:52.125Z,1724100592.125 [DATMMP](INFO): Starting 2024-08-19T20:49:52.377Z,1724100592.377 [DATMMP](INFO): Starting 2024-08-19T20:49:52.629Z,1724100592.629 [DATMMP](INFO): Starting 2024-08-19T20:49:52.881Z,1724100592.881 [DATMMP](INFO): Starting 2024-08-19T20:49:53.133Z,1724100593.133 [DATMMP](INFO): Starting 2024-08-19T20:49:53.385Z,1724100593.385 [DATMMP](INFO): Starting 2024-08-19T20:49:53.638Z,1724100593.638 [DATMMP](INFO): Starting 2024-08-19T20:49:53.889Z,1724100593.889 [DATMMP](INFO): Starting 2024-08-19T20:49:53.941Z,1724100593.941 [DATMMP](INFO): entering command mode 2024-08-19T20:49:54.157Z,1724100594.157 [DATMMP](INFO): Starting 2024-08-19T20:49:54.157Z,1724100594.157 [DATMMP](INFO): DAT read: 2024-08-19T20:49:54.158Z,1724100594.158 [DATMMP](INFO): DAT read: user:1> 2024-08-19T20:49:54.158Z,1724100594.158 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-08-19T20:49:54.158Z,1724100594.158 [DATMMP](INFO): entering MMP mode 2024-08-19T20:49:54.409Z,1724100594.409 [DATMMP](INFO): Starting 2024-08-19T20:49:54.410Z,1724100594.410 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-19T20:49:54.412Z,1724100594.412 [DATMMP](INFO): status rx: x1 2024-08-19T20:49:54.412Z,1724100594.412 [DATMMP](INFO): MMP status: 1: started 2024-08-19T20:49:54.412Z,1724100594.412 [DATMMP](INFO): Received message type: status 2024-08-19T20:49:54.412Z,1724100594.412 [DATMMP](INFO): Handled 2024-08-19T20:49:54.661Z,1724100594.661 [DATMMP](INFO): Starting 2024-08-19T20:49:54.662Z,1724100594.662 [DATMMP](INFO): Sent Tx Power Config 8 2024-08-19T20:49:54.663Z,1724100594.663 [DATMMP](INFO): Setting local address: 2 2024-08-19T20:49:54.664Z,1724100594.664 [DATMMP](INFO): Setting time to: 20:49:54 And date to:8/19/2024 2024-08-19T20:49:54.913Z,1724100594.913 [DATMMP](INFO): Starting 2024-08-19T20:49:54.914Z,1724100594.914 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-19T20:49:54.914Z,1724100594.914 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-08-19T20:49:54.915Z,1724100594.915 [DATMMP](INFO): Received message type: mdmpower 2024-08-19T20:49:54.915Z,1724100594.915 [DATMMP](INFO): Handled 2024-08-19T20:49:55.165Z,1724100595.165 [DATMMP](INFO): Starting 2024-08-19T20:49:55.166Z,1724100595.166 [DATMMP](INFO): GSXN notify for xid: 10 2024-08-19T20:49:55.166Z,1724100595.166 [DATMMP](INFO): sreg_txpower rx: x8 2024-08-19T20:49:55.166Z,1724100595.166 [DATMMP](INFO): Received message type: sreg_txpower 2024-08-19T20:49:55.167Z,1724100595.167 [DATMMP](INFO): Match for : sreg_txpower 2024-08-19T20:49:55.167Z,1724100595.167 [DATMMP](INFO): ACK 2024-08-19T20:49:55.417Z,1724100595.417 [DATMMP](INFO): Starting 2024-08-19T20:49:55.418Z,1724100595.418 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-19T20:49:55.418Z,1724100595.418 [DATMMP](INFO): mdmlocal rx: x2 x0 x0 x0 2024-08-19T20:49:55.419Z,1724100595.419 [DATMMP](INFO): Received message type: mdmlocal 2024-08-19T20:49:55.419Z,1724100595.419 [DATMMP](INFO): Handled 2024-08-19T20:49:55.669Z,1724100595.669 [DATMMP](INFO): Starting 2024-08-19T20:49:55.670Z,1724100595.670 [DATMMP](INFO): GSXN notify for xid: 11 2024-08-19T20:49:55.670Z,1724100595.670 [DATMMP](INFO): sreg_locaddr rx: x2 2024-08-19T20:49:55.670Z,1724100595.670 [DATMMP](INFO): Received message type: sreg_locaddr 2024-08-19T20:49:55.670Z,1724100595.670 [DATMMP](INFO): Match for : sreg_locaddr 2024-08-19T20:49:55.671Z,1724100595.671 [DATMMP](INFO): ACK 2024-08-19T20:49:55.921Z,1724100595.921 [DATMMP](INFO): Starting 2024-08-19T20:49:55.922Z,1724100595.922 [DATMMP](INFO): GSXN notify for xid: 12 2024-08-19T20:49:55.923Z,1724100595.923 [DATMMP](INFO): timedate rx: x0 x14 x31 x36 x7 x13 x7C x0 2024-08-19T20:49:55.923Z,1724100595.923 [DATMMP](INFO): Received message type: timedate 2024-08-19T20:49:55.923Z,1724100595.923 [DATMMP](INFO): Match for : timedate 2024-08-19T20:49:55.923Z,1724100595.923 [DATMMP](INFO): ACK 2024-08-19T20:49:56.173Z,1724100596.173 [DATMMP](INFO): Starting 2024-08-19T20:49:56.174Z,1724100596.174 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-19T20:49:56.175Z,1724100596.175 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-08-19T20:49:56.175Z,1724100596.175 [DATMMP](INFO): Received message type: sys_verb 2024-08-19T20:49:56.175Z,1724100596.175 [DATMMP](INFO): Handled 2024-08-19T20:49:56.425Z,1724100596.425 [DATMMP](INFO): Starting 2024-08-19T20:49:56.426Z,1724100596.426 [DATMMP](INFO): GSXN notify for xid: 13 2024-08-19T20:49:56.426Z,1724100596.426 [DATMMP](INFO): sreg_verbosity rx: x0 2024-08-19T20:49:56.426Z,1724100596.426 [DATMMP](INFO): Received message type: sreg_verbosity 2024-08-19T20:49:56.426Z,1724100596.426 [DATMMP](INFO): Match for : sreg_verbosity 2024-08-19T20:49:56.427Z,1724100596.427 [DATMMP](INFO): ACK 2024-08-19T20:49:56.677Z,1724100596.677 [DATMMP](INFO): Starting 2024-08-19T20:49:56.678Z,1724100596.678 [DATMMP](INFO): GSXN notify for xid: 14 2024-08-19T20:49:56.678Z,1724100596.678 [DATMMP](INFO): version rx: x2 x5 2024-08-19T20:49:56.679Z,1724100596.679 [DATMMP](INFO): DatMMPVersion: 2.5 2024-08-19T20:49:56.679Z,1724100596.679 [DATMMP](INFO): Received message type: version 2024-08-19T20:49:56.679Z,1724100596.679 [DATMMP](INFO): Match for : version 2024-08-19T20:49:56.679Z,1724100596.679 [DATMMP](INFO): ACK 2024-08-19T20:49:56.679Z,1724100596.679 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-08-19T20:49:58.215Z,1724100598.215 [SBIT](IMPORTANT): Beginning Startup BIT 2024-08-19T20:49:58.219Z,1724100598.219 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-19T20:50:06.239Z,1724100606.239 [NAL9602](INFO): Powering up NAL9602 2024-08-19T20:50:09.125Z,1724100609.125 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.001672 CHAN A1 (24V): 0.054407 CHAN A2 (12V): 0.000593 CHAN A3 (5V): -0.002251 CHAN B0 (3.3V): -0.000614 CHAN B1 (3.15aV): -0.000690 CHAN B2 (3.15bV): -0.001305 CHAN B3 (GND): -0.002493 OPEN: 0.004776 Full Scale: +/- 1 mA 2024-08-19T20:50:11.302Z,1724100611.302 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-19T20:50:11.303Z,1724100611.303 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-08-19T20:50:11.303Z,1724100611.303 [DATMMP](INFO): Received message type: timesync 2024-08-19T20:50:11.303Z,1724100611.303 [DATMMP](INFO): Handled 2024-08-19T20:50:11.535Z,1724100611.535 [SBIT](ERROR): Could not read massPosReader_. 2024-08-19T20:50:17.151Z,1724100617.151 [NAL9602](INFO): NAL9602 initialized 2024-08-19T20:50:21.138Z,1724100621.138 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-19T20:50:21.139Z,1724100621.139 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-08-19T20:50:21.139Z,1724100621.139 [DATMMP](INFO): Received message type: timesync 2024-08-19T20:50:21.139Z,1724100621.139 [DATMMP](INFO): Handled 2024-08-19T20:50:51.542Z,1724100651.542 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:50:51.953Z,1724100651.953 [SBIT](CRITICAL): SBIT FAILED 2024-08-19T20:50:51.953Z,1724100651.953 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-08-19T20:50:51.954Z,1724100651.954 [SBIT](IMPORTANT): 2024-08-19T20:50:51.954Z,1724100651.954 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool; 2024-08-19T20:50:51.954Z,1724100651.954 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-08-19T20:50:51.955Z,1724100651.955 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-08-19T20:50:51.955Z,1724100651.955 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-08-19T20:50:51.955Z,1724100651.955 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=-0.1 meter; 2024-08-19T20:50:51.955Z,1724100651.955 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-08-19T20:50:51.955Z,1724100651.955 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2024-08-19T20:50:51.955Z,1724100651.955 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-08-19T20:50:51.955Z,1724100651.955 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2024-08-19T20:50:51.957Z,1724100651.957 [CommandExec](FAULT): Scheduling is paused 2024-08-19T20:50:51.958Z,1724100651.958 [CBIT](INFO): Critical error at 20240819T205051 2024-08-19T20:50:51.959Z,1724100651.959 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-08-19T20:50:52.404Z,1724100652.404 [MissionManager](IMPORTANT): Started mission Startup 2024-08-19T20:50:52.404Z,1724100652.404 [Startup] Running Loop=1 2024-08-19T20:50:52.404Z,1724100652.404 [Startup](DEBUG): Aggregate::initialize Startup 2024-08-19T20:50:52.404Z,1724100652.404 [Startup:A.GoToSurface] Running Loop=1 2024-08-19T20:50:52.404Z,1724100652.404 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-19T20:50:52.405Z,1724100652.405 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-19T20:50:52.405Z,1724100652.405 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-19T20:50:52.406Z,1724100652.406 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-19T20:50:52.406Z,1724100652.406 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-19T20:50:52.406Z,1724100652.406 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-19T20:50:52.407Z,1724100652.407 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-19T20:50:52.408Z,1724100652.408 [Startup:StartupSatComms] Running Loop=1 2024-08-19T20:50:52.408Z,1724100652.408 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-08-19T20:50:52.408Z,1724100652.408 [Startup:StartupSatComms:A] Running Loop=1 2024-08-19T20:50:52.713Z,1724100652.713 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-08-19T20:51:17.801Z,1724100677.801 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:51:30.428Z,1724100690.428 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:51:41.525Z,1724100701.525 [CommandExec](IMPORTANT): got command configSet DATMMP.surfaceThreshold 5 meter persist 2024-08-19T20:51:52.526Z,1724100712.526 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-19T20:50:52.4Z 2024-08-19T20:51:52.526Z,1724100712.526 [Startup:StartupSatComms:A] Stopped 2024-08-19T20:51:52.526Z,1724100712.526 [Startup:StartupSatComms:B] Running Loop=1 2024-08-19T20:51:53.007Z,1724100713.007 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-19T20:51:54.336Z,1724100714.336 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004945 2024-08-19T20:52:37.739Z,1724100757.739 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-08-19T20:52:37.739Z,1724100757.739 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-08-19T20:52:37.740Z,1724100757.740 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-08-19T20:52:37.740Z,1724100757.740 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-08-19T20:52:37.751Z,1724100757.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-08-19T20:52:37.751Z,1724100757.751 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-08-19T20:52:38.112Z,1724100758.112 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-08-19T20:52:38.112Z,1724100758.112 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-19T20:52:38.123Z,1724100758.123 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-19T20:52:38.123Z,1724100758.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-08-19T20:52:38.123Z,1724100758.123 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-08-19T20:52:38.124Z,1724100758.124 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-08-19T20:52:38.124Z,1724100758.124 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-08-19T20:52:38.694Z,1724100758.694 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-08-19T20:52:38.735Z,1724100758.735 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-19T20:52:38.735Z,1724100758.735 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-08-19T20:52:51.884Z,1724100771.884 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:52:52.683Z,1724100772.683 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-19T20:51:52.5Z 2024-08-19T20:52:52.683Z,1724100772.683 [Startup:StartupSatComms:B] Stopped 2024-08-19T20:52:52.683Z,1724100772.683 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-08-19T20:52:52.683Z,1724100772.683 [Startup:StartupSatComms] Stopped 2024-08-19T20:52:52.683Z,1724100772.683 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-08-19T20:52:52.684Z,1724100772.684 [Startup](INFO): Completed Startup 2024-08-19T20:52:52.684Z,1724100772.684 [MissionManager](INFO): Startup is completed. 2024-08-19T20:52:52.684Z,1724100772.684 [MissionManager](INFO): Uninitializing Mission Startup 2024-08-19T20:52:52.684Z,1724100772.684 [Startup] Stopped 2024-08-19T20:52:52.685Z,1724100772.685 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-08-19T20:52:52.685Z,1724100772.685 [Startup:A.GoToSurface] Stopped 2024-08-19T20:52:52.685Z,1724100772.685 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-19T20:52:53.072Z,1724100773.072 [MissionManager](IMPORTANT): Started mission Default 2024-08-19T20:52:53.072Z,1724100773.072 [Default] Running Loop=1 2024-08-19T20:52:53.073Z,1724100773.073 [Default](DEBUG): Aggregate::initialize Default 2024-08-19T20:52:53.073Z,1724100773.073 [Default:B.GoToSurface] Running Loop=1 2024-08-19T20:52:53.073Z,1724100773.073 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-19T20:52:53.073Z,1724100773.073 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-19T20:52:53.074Z,1724100773.074 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-19T20:52:53.074Z,1724100773.074 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-19T20:52:53.074Z,1724100773.074 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-19T20:52:53.074Z,1724100773.074 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-19T20:52:53.075Z,1724100773.075 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-19T20:52:53.075Z,1724100773.075 [Default:A.Wait] Running Loop=1 2024-08-19T20:52:53.075Z,1724100773.075 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-08-19T20:53:01.588Z,1724100781.588 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree 2024-08-19T20:53:01.595Z,1724100781.595 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2024-08-19T20:53:01.596Z,1724100781.596 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree 2024-08-19T20:53:01.604Z,1724100781.604 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2024-08-19T20:53:02.022Z,1724100782.022 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-08-19T20:53:06.395Z,1724100786.395 [Default:A.Wait](INFO): Done Waiting. 2024-08-19T20:53:06.395Z,1724100786.395 [Default:A.Wait] Stopped 2024-08-19T20:53:06.395Z,1724100786.395 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-08-19T20:53:06.808Z,1724100786.808 [Default:CheckIn] Running Loop=1 2024-08-19T20:53:06.808Z,1724100786.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-19T20:53:06.808Z,1724100786.808 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-19T20:53:07.197Z,1724100787.197 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-19T20:53:12.148Z,1724100792.148 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1724100786 second 2024-08-19T20:53:12.454Z,1724100792.454 [Default:CheckIn:Read_GPS] Stopped 2024-08-19T20:53:12.454Z,1724100792.454 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-19T20:53:12.874Z,1724100792.874 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-19T20:53:48.304Z,1724100828.304 [CommandExec](IMPORTANT): got command get DataOverHttps.loadAtStartup 2024-08-19T20:53:48.305Z,1724100828.305 [CommandExec](IMPORTANT): DataOverHttps.loadAtStartup 1 bool 2024-08-19T20:53:54.650Z,1724100834.650 [CommandExec](IMPORTANT): got command show stack 2024-08-19T20:53:54.650Z,1724100834.650 [CommandExec](IMPORTANT): Behavior Stack: 2024-08-19T20:53:54.651Z,1724100834.651 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2024-08-19T20:53:54.651Z,1724100834.651 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium 2024-08-19T20:54:05.519Z,1724100845.519 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1724100834 second 2024-08-19T20:54:17.880Z,1724100857.880 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:54:31.616Z,1724100871.616 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:54:45.756Z,1724100885.756 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:54:49.598Z,1724100889.598 [CommandExec](IMPORTANT): got command show stack 2024-08-19T20:54:49.599Z,1724100889.599 [CommandExec](IMPORTANT): Behavior Stack: 2024-08-19T20:54:49.599Z,1724100889.599 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2024-08-19T20:54:49.599Z,1724100889.599 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium 2024-08-19T20:54:51.466Z,1724100891.466 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:55:00.703Z,1724100900.703 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:55:13.685Z,1724100913.685 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-19T20:55:20.097Z,1724100920.097 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-19T20:55:27.771Z,1724100927.771 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:55:41.508Z,1724100941.508 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:55:50.814Z,1724100950.814 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-19T20:55:55.647Z,1724100955.647 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:56:01.363Z,1724100961.363 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:56:08.204Z,1724100968.204 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-19T20:56:21.146Z,1724100981.146 [CommandExec](IMPORTANT): got command show stack 2024-08-19T20:56:21.146Z,1724100981.146 [CommandExec](IMPORTANT): Behavior Stack: 2024-08-19T20:56:21.147Z,1724100981.147 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2024-08-19T20:56:21.147Z,1724100981.147 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium 2024-08-19T20:56:22.720Z,1724100982.720 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T20:56:30.214Z,1724100990.214 [CommandExec](IMPORTANT): got command show stack 2024-08-19T20:56:30.214Z,1724100990.214 [CommandExec](IMPORTANT): Behavior Stack: 2024-08-19T20:56:30.215Z,1724100990.215 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2024-08-19T20:56:30.215Z,1724100990.215 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium 2024-08-19T20:56:31.837Z,1724100991.837 [CommandExec](IMPORTANT): got command get depth 2024-08-19T20:56:31.838Z,1724100991.838 [CommandExec](IMPORTANT): depth -0.005180 m 2024-08-19T20:56:59.372Z,1724101019.372 [CommandExec](IMPORTANT): got command get DATMMP.surfaceThreshold 2024-08-19T20:56:59.373Z,1724101019.373 [CommandExec](IMPORTANT): DATMMP.surfaceThreshold 5.000000 m 2024-08-19T20:57:05.779Z,1724101025.779 [CommandExec](IMPORTANT): got command show variable dataoverhttps 2024-08-19T20:57:05.892Z,1724101025.892 [CommandExec](IMPORTANT): DataOverHttps.loadAtStartup (bool) 2024-08-19T20:57:05.892Z,1724101025.892 [CommandExec](IMPORTANT): DataOverHttps.simulateHardware (bool) 2024-08-19T20:57:05.892Z,1724101025.892 [CommandExec](IMPORTANT): DataOverHttps.connectionTimeout (second) 2024-08-19T20:57:05.893Z,1724101025.893 [CommandExec](IMPORTANT): DataOverHttps.period (second) 2024-08-19T20:57:05.897Z,1724101025.897 [CommandExec](IMPORTANT): DataOverHttps.power (watt) 2024-08-19T20:57:05.898Z,1724101025.898 [CommandExec](IMPORTANT): DataOverHttps.timeout (minute) 2024-08-19T20:57:05.898Z,1724101025.898 [CommandExec](IMPORTANT): DataOverHttps.verbosity (count) 2024-08-19T20:57:06.109Z,1724101026.109 [CommandExec](IMPORTANT): DataOverHttps.enableBroadcast (bool) 2024-08-19T20:57:06.117Z,1724101026.117 [CommandExec](IMPORTANT): DataOverHttps.platform_communications (bool) 2024-08-19T20:57:06.118Z,1724101026.118 [CommandExec](IMPORTANT): DataOverHttps.connectionStatus (bool) 2024-08-19T20:57:14.365Z,1724101034.365 [CommandExec](IMPORTANT): got command get DataOverHttps.connectionStatus 2024-08-19T20:57:14.366Z,1724101034.366 [CommandExec](IMPORTANT): DataOverHttps.connectionStatus 1 bool 2024-08-19T20:57:24.850Z,1724101044.850 [CommandExec](IMPORTANT): got command get DataOverHttps.simulateHardware 2024-08-19T20:57:24.851Z,1724101044.851 [CommandExec](IMPORTANT): DataOverHttps.simulateHardware 0 bool 2024-08-19T20:57:35.594Z,1724101055.594 [CommandExec](IMPORTANT): got command get DataOverHttps.timeout 2024-08-19T20:57:35.594Z,1724101055.594 [CommandExec](IMPORTANT): DataOverHttps.timeout 3.000000 min 2024-08-19T20:58:03.399Z,1724101083.399 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:58:16.348Z,1724101096.348 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:59:08.895Z,1724101148.895 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-19T20:59:24.658Z,1724101164.658 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:59:51.322Z,1724101191.322 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T20:59:54.707Z,1724101194.707 [CommandExec](IMPORTANT): got command show variable sendData 2024-08-19T20:59:54.723Z,1724101194.723 [CommandExec](IMPORTANT): Vehicle.sendDataToShore (bool) 2024-08-19T21:00:02.063Z,1724101202.063 [CommandExec](IMPORTANT): got command get Vehicle.sendDataToShore 2024-08-19T21:00:02.063Z,1724101202.063 [CommandExec](IMPORTANT): Vehicle.sendDataToShore 1 bool 2024-08-19T21:01:44.830Z,1724101304.830 [CommandExec](IMPORTANT): got command failComponent 2024-08-19T21:01:44.830Z,1724101304.830 [CommandExec](IMPORTANT): Failed components: 2024-08-19T21:01:44.830Z,1724101304.830 [CommandExec](IMPORTANT): No failed Components. 2024-08-19T21:01:53.298Z,1724101313.298 [CommandExec](IMPORTANT): got command show stack 2024-08-19T21:01:53.298Z,1724101313.298 [CommandExec](IMPORTANT): Behavior Stack: 2024-08-19T21:01:53.299Z,1724101313.299 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2024-08-19T21:01:53.299Z,1724101313.299 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium 2024-08-19T21:01:58.684Z,1724101318.684 [CommandExec](IMPORTANT): got command get depth 2024-08-19T21:01:58.684Z,1724101318.684 [CommandExec](IMPORTANT): depth 0.007326 m 2024-08-19T21:02:13.321Z,1724101333.321 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool 2024-08-19T21:02:13.525Z,1724101333.525 [Default:CheckIn:Read_Iridium] Stopped 2024-08-19T21:02:13.525Z,1724101333.525 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-19T21:02:13.525Z,1724101333.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-19T21:02:20.747Z,1724101340.747 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2024-08-19T21:02:26.191Z,1724101346.191 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1724101333 second 2024-08-19T21:02:35.765Z,1724101355.765 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T21:02:57.614Z,1724101377.614 [DataOverHttps](IMPORTANT): SBD MTMSN=20240819T210256 2024-08-19T21:03:05.185Z,1724101385.185 [DataOverHttps](INFO): Received command: failc 2024-08-19T21:03:05.242Z,1724101385.242 [CommandExec](IMPORTANT): got command failComponent 2024-08-19T21:03:05.242Z,1724101385.242 [CommandExec](IMPORTANT): Failed components: 2024-08-19T21:03:05.242Z,1724101385.242 [CommandExec](IMPORTANT): No failed Components. 2024-08-19T21:03:26.643Z,1724101406.643 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T21:03:28.673Z,1724101408.673 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-19T21:03:41.605Z,1724101421.605 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-19T21:03:51.296Z,1724101431.296 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T21:03:56.530Z,1724101436.530 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-19T21:04:10.227Z,1724101450.227 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T21:04:23.578Z,1724101463.578 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T21:04:27.661Z,1724101467.661 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-19T21:04:37.699Z,1724101477.699 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-08-19T21:04:37.838Z,1724101477.838 [DataOverHttps](IMPORTANT): SBD MTMSN=20240819T210437 2024-08-19T21:04:45.153Z,1724101485.153 [DataOverHttps](INFO): Received command: restart sys 2024-08-19T21:04:45.218Z,1724101485.218 [CommandExec](IMPORTANT): got command restart system 2024-08-19T21:04:47.293Z,1724101487.293 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-19T21:04:47.294Z,1724101487.294 [CommandExec](INFO): Uninitializing the command executive. 2024-08-19T21:04:47.294Z,1724101487.294 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-19T21:04:47.294Z,1724101487.294 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:47.295Z,1724101487.295 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-19T21:04:47.295Z,1724101487.295 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-19T21:04:47.295Z,1724101487.295 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:47.296Z,1724101487.296 [NavChartDb](INFO): Join timeout helper Thread ID is 1345 2024-08-19T21:04:47.553Z,1724101487.553 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-19T21:04:47.554Z,1724101487.554 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:47.569Z,1724101487.569 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-08-19T21:04:47.569Z,1724101487.569 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:47.570Z,1724101487.570 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1346 2024-08-19T21:04:47.725Z,1724101487.725 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-19T21:04:47.726Z,1724101487.726 [WetLabsBB2FL](INFO): Powering down 2024-08-19T21:04:47.726Z,1724101487.726 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:47.733Z,1724101487.733 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-08-19T21:04:47.733Z,1724101487.733 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:47.734Z,1724101487.734 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1347 2024-08-19T21:04:48.725Z,1724101488.725 [CTD_Seabird](INFO): Powering down 2024-08-19T21:04:48.737Z,1724101488.737 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-19T21:04:48.737Z,1724101488.737 [CTD_Seabird](INFO): Powering down 2024-08-19T21:04:48.749Z,1724101488.749 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:48.766Z,1724101488.766 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-19T21:04:48.766Z,1724101488.766 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:48.766Z,1724101488.766 [Radio_Surface](INFO): Join timeout helper Thread ID is 1348 2024-08-19T21:04:48.917Z,1724101488.917 [Radio_Surface](INFO): Powering down 2024-08-19T21:04:48.918Z,1724101488.918 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-19T21:04:48.919Z,1724101488.919 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:48.937Z,1724101488.937 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-08-19T21:04:48.937Z,1724101488.937 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-19T21:04:48.937Z,1724101488.937 [Onboard](INFO): Join timeout helper Thread ID is 1349 2024-08-19T21:04:49.165Z,1724101489.165 [DataOverHttps](INFO): Radio surface powered OFF, will not co