2022-05-04T22:17:29.907Z,1651702649.907 [Supervisor](DEBUG): Initializing supervisor. 2022-05-04T22:17:29.912Z,1651702649.912 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-05-04T22:17:29.913Z,1651702649.913 [SyncHandler](INFO): Protected caller Thread ID is 1031 2022-05-04T22:17:29.913Z,1651702649.913 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-05-04T22:17:29.915Z,1651702649.915 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-05-04T22:17:29.915Z,1651702649.915 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1032 2022-05-04T22:17:29.919Z,1651702649.919 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-05-04T22:17:29.938Z,1651702649.938 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-05-04T22:17:29.939Z,1651702649.939 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-05-04T22:17:29.939Z,1651702649.939 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1033 2022-05-04T22:17:29.943Z,1651702649.943 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-05-04T22:17:29.944Z,1651702649.944 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-05-04T22:17:29.944Z,1651702649.944 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1034 2022-05-04T22:17:29.946Z,1651702649.946 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-05-04T22:17:29.947Z,1651702649.947 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-05-04T22:17:29.948Z,1651702649.948 [logger ThreadHandler](INFO): Protected caller Thread ID is 1035 2022-05-04T22:17:29.951Z,1651702649.951 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-05-04T22:17:29.952Z,1651702649.952 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-05-04T22:17:29.953Z,1651702649.953 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-05-04T22:17:30.048Z,1651702650.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-05-04T22:17:30.049Z,1651702650.049 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-05-04T22:17:30.263Z,1651702650.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-05-04T22:17:30.263Z,1651702650.263 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-05-04T22:17:30.527Z,1651702650.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-05-04T22:17:30.528Z,1651702650.528 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-05-04T22:17:30.634Z,1651702650.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-05-04T22:17:30.635Z,1651702650.635 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-05-04T22:17:31.279Z,1651702651.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-05-04T22:17:31.280Z,1651702651.280 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-05-04T22:17:31.724Z,1651702651.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-05-04T22:17:31.725Z,1651702651.725 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-05-04T22:17:32.289Z,1651702652.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-05-04T22:17:32.290Z,1651702652.290 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-05-04T22:17:32.747Z,1651702652.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-05-04T22:17:32.748Z,1651702652.748 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-05-04T22:17:33.343Z,1651702653.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-05-04T22:17:33.344Z,1651702653.344 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-05-04T22:17:33.713Z,1651702653.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-05-04T22:17:33.714Z,1651702653.714 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-05-04T22:17:33.932Z,1651702653.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-05-04T22:17:33.932Z,1651702653.932 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-05-04T22:17:34.062Z,1651702654.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-05-04T22:17:34.063Z,1651702654.063 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-05-04T22:17:34.143Z,1651702654.143 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-05-04T22:17:34.222Z,1651702654.222 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-05-04T22:17:34.408Z,1651702654.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-05-04T22:17:34.408Z,1651702654.408 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-05-04T22:17:34.625Z,1651702654.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-05-04T22:17:34.627Z,1651702654.627 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2022-05-04T22:17:34.628Z,1651702654.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2022-05-04T22:17:34.710Z,1651702654.710 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2022-05-04T22:17:34.970Z,1651702654.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-05-04T22:17:34.971Z,1651702654.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2022-05-04T22:17:35.067Z,1651702655.067 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2022-05-04T22:17:35.230Z,1651702655.230 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2022-05-04T22:17:35.411Z,1651702655.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2022-05-04T22:17:35.496Z,1651702655.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2022-05-04T22:17:35.601Z,1651702655.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2022-05-04T22:17:35.702Z,1651702655.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2022-05-04T22:17:35.862Z,1651702655.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2022-05-04T22:17:35.965Z,1651702655.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2022-05-04T22:17:36.065Z,1651702656.065 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2022-05-04T22:17:36.065Z,1651702656.065 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-05-04T22:17:36.080Z,1651702656.080 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-05-04T22:17:36.190Z,1651702656.190 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-05-04T22:17:36.191Z,1651702656.191 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-05-04T22:17:36.337Z,1651702656.337 [BuoyancyServo] Loaded 2022-05-04T22:17:36.338Z,1651702656.338 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-05-04T22:17:36.358Z,1651702656.358 [ElevatorServo] Loaded 2022-05-04T22:17:36.358Z,1651702656.358 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-05-04T22:17:36.374Z,1651702656.374 [ThrusterHE] Loaded 2022-05-04T22:17:36.374Z,1651702656.374 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-05-04T22:17:36.375Z,1651702656.375 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-05-04T22:17:36.375Z,1651702656.375 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-05-04T22:17:36.517Z,1651702656.517 [SBIT](DEBUG): Construct Startup Built In Test. 2022-05-04T22:17:36.533Z,1651702656.533 [SBIT] Loaded 2022-05-04T22:17:36.533Z,1651702656.533 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-05-04T22:17:36.536Z,1651702656.536 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-05-04T22:17:36.557Z,1651702656.557 [IBIT] Loaded 2022-05-04T22:17:36.557Z,1651702656.557 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-05-04T22:17:36.562Z,1651702656.562 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-05-04T22:17:36.775Z,1651702656.775 [CBIT] Loaded 2022-05-04T22:17:36.776Z,1651702656.776 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-05-04T22:17:36.776Z,1651702656.776 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-05-04T22:17:36.777Z,1651702656.777 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-05-04T22:17:36.915Z,1651702656.915 [ESPComponent] Loaded 2022-05-04T22:17:36.915Z,1651702656.915 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2022-05-04T22:17:36.934Z,1651702656.934 [PAR_Licor] Loaded 2022-05-04T22:17:36.934Z,1651702656.934 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-05-04T22:17:36.965Z,1651702656.965 [WetLabsBB2FL] Loaded 2022-05-04T22:17:36.966Z,1651702656.966 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-05-04T22:17:36.967Z,1651702656.967 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406614E0 2022-05-04T22:17:36.967Z,1651702656.967 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1117 2022-05-04T22:17:36.968Z,1651702656.968 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-05-04T22:17:36.969Z,1651702656.969 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-05-04T22:17:37.018Z,1651702657.018 [DepthRateCalculator] Loaded 2022-05-04T22:17:37.019Z,1651702657.019 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-05-04T22:17:37.024Z,1651702657.024 [PitchRateCalculator] Loaded 2022-05-04T22:17:37.024Z,1651702657.024 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-05-04T22:17:37.034Z,1651702657.034 [SpeedCalculator] Loaded 2022-05-04T22:17:37.035Z,1651702657.035 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-05-04T22:17:37.039Z,1651702657.039 [YawRateCalculator] Loaded 2022-05-04T22:17:37.040Z,1651702657.040 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-05-04T22:17:37.058Z,1651702657.058 [ElevatorOffsetCalculator] Loaded 2022-05-04T22:17:37.058Z,1651702657.058 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-05-04T22:17:37.075Z,1651702657.075 [ShortestDistanceToPolygonSidesCalculator] Loaded 2022-05-04T22:17:37.075Z,1651702657.075 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread. 2022-05-04T22:17:37.075Z,1651702657.075 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-05-04T22:17:37.076Z,1651702657.076 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-05-04T22:17:37.171Z,1651702657.171 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-05-04T22:17:37.171Z,1651702657.171 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-05-04T22:17:37.197Z,1651702657.197 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-05-04T22:17:37.198Z,1651702657.198 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-05-04T22:17:37.319Z,1651702657.319 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-05-04T22:17:37.319Z,1651702657.319 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-05-04T22:17:37.333Z,1651702657.333 [NavChart] Loaded 2022-05-04T22:17:37.333Z,1651702657.333 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-05-04T22:17:37.339Z,1651702657.339 [UniversalFixResidualReporter] Loaded 2022-05-04T22:17:37.339Z,1651702657.339 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-05-04T22:17:37.339Z,1651702657.339 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-05-04T22:17:37.340Z,1651702657.340 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-05-04T22:17:37.853Z,1651702657.853 [AHRS_M2] Loaded 2022-05-04T22:17:37.853Z,1651702657.853 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-05-04T22:17:37.927Z,1651702657.927 [DataOverHttps] Loaded 2022-05-04T22:17:37.927Z,1651702657.927 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-05-04T22:17:37.928Z,1651702657.928 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0 2022-05-04T22:17:37.929Z,1651702657.929 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1118 2022-05-04T22:17:37.948Z,1651702657.948 [Depth_Keller] Loaded 2022-05-04T22:17:37.949Z,1651702657.949 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-05-04T22:17:37.984Z,1651702657.984 [DVL_micro] Loaded 2022-05-04T22:17:37.985Z,1651702657.985 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2022-05-04T22:17:38.041Z,1651702658.041 [NAL9602] Loaded 2022-05-04T22:17:38.042Z,1651702658.042 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-05-04T22:17:38.067Z,1651702658.067 [Onboard] Loaded 2022-05-04T22:17:38.067Z,1651702658.067 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-05-04T22:17:38.068Z,1651702658.068 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0 2022-05-04T22:17:38.068Z,1651702658.068 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1119 2022-05-04T22:17:38.080Z,1651702658.080 [Power24vConverter] Loaded 2022-05-04T22:17:38.080Z,1651702658.080 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-05-04T22:17:38.093Z,1651702658.093 [Radio_Surface] Loaded 2022-05-04T22:17:38.093Z,1651702658.093 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-05-04T22:17:38.094Z,1651702658.094 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A3D4E0 2022-05-04T22:17:38.094Z,1651702658.094 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1120 2022-05-04T22:17:38.095Z,1651702658.095 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-05-04T22:17:38.096Z,1651702658.096 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-05-04T22:17:38.182Z,1651702658.182 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-05-04T22:17:38.183Z,1651702658.183 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-05-04T22:17:38.268Z,1651702658.268 [VerticalControl](DEBUG): Construct VerticalControl. 2022-05-04T22:17:38.320Z,1651702658.320 [VerticalControl] Loaded 2022-05-04T22:17:38.321Z,1651702658.321 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-05-04T22:17:38.325Z,1651702658.325 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-05-04T22:17:38.508Z,1651702658.508 [HorizontalControl] Loaded 2022-05-04T22:17:38.508Z,1651702658.508 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-05-04T22:17:38.557Z,1651702658.557 [SpeedControl](DEBUG): Construct SpeedControl. 2022-05-04T22:17:38.559Z,1651702658.559 [SpeedControl] Loaded 2022-05-04T22:17:38.559Z,1651702658.559 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-05-04T22:17:38.562Z,1651702658.562 [LoopControl](DEBUG): Construct LoopControl. 2022-05-04T22:17:38.562Z,1651702658.562 [LoopControl] Loaded 2022-05-04T22:17:38.562Z,1651702658.562 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-05-04T22:17:38.563Z,1651702658.563 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-05-04T22:17:38.563Z,1651702658.563 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-05-04T22:17:38.601Z,1651702658.601 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-05-04T22:17:38.601Z,1651702658.601 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-05-04T22:17:39.230Z,1651702659.230 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-05-04T22:17:39.236Z,1651702659.236 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-05-04T22:17:39.239Z,1651702659.239 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-05-04T22:17:39.249Z,1651702659.249 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-05-04T22:17:39.250Z,1651702659.250 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0 2022-05-04T22:17:39.251Z,1651702659.251 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1121 2022-05-04T22:17:39.255Z,1651702659.255 [Supervisor](INFO): Main Thread ID is 823 2022-05-04T22:17:39.255Z,1651702659.255 [Supervisor](DEBUG): Running supervisor. 2022-05-04T22:17:39.256Z,1651702659.256 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1122 2022-05-04T22:17:39.256Z,1651702659.256 [CommandExec](INFO): Initializing the command executive. 2022-05-04T22:17:39.258Z,1651702659.258 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1123 2022-05-04T22:17:39.260Z,1651702659.260 [controlThread ThreadHandler](INFO): Handler Thread ID is 1124 2022-05-04T22:17:39.260Z,1651702659.260 [controlThread](DEBUG): Initializing ControlThread 2022-05-04T22:17:39.262Z,1651702659.262 [SBIT](INFO): Initialize SBIT Component. 2022-05-04T22:17:39.262Z,1651702659.262 [SBIT](IMPORTANT): git: 2022-04-25-37-g2a944135f 2022-05-04T22:17:39.263Z,1651702659.263 [SBIT](INFO): git hash: 2a944135f4713de004d013a6b17eee164aec7a5a 2022-05-04T22:17:39.263Z,1651702659.263 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-05-04T22:17:39.264Z,1651702659.264 [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 2022-05-04T22:17:39.265Z,1651702659.265 [SBIT](INFO): Beginning SBIT in 59.000000 seconds. 2022-05-04T22:17:39.266Z,1651702659.266 [IBIT](INFO): Initialize IBIT Component. 2022-05-04T22:17:39.267Z,1651702659.267 [CBIT](DEBUG): Initialize CBIT Component. 2022-05-04T22:17:39.268Z,1651702659.268 [logger ThreadHandler](INFO): Handler Thread ID is 1125 2022-05-04T22:17:39.280Z,1651702659.280 [CBIT](DEBUG): Initialized mux pins. 2022-05-04T22:17:39.280Z,1651702659.280 [CBIT](DEBUG): Initializing the watchdog timer. 2022-05-04T22:17:39.288Z,1651702659.288 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1126 2022-05-04T22:17:39.293Z,1651702659.293 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:17:39.294Z,1651702659.294 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1128 2022-05-04T22:17:39.295Z,1651702659.295 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-05-04T22:17:39.304Z,1651702659.304 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-05-04T22:17:39.304Z,1651702659.304 [CBIT](DEBUG): Initializing heartbeat. 2022-05-04T22:17:39.305Z,1651702659.305 [Onboard ThreadHandler](INFO): Handler Thread ID is 1129 2022-05-04T22:17:39.334Z,1651702659.334 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1130 2022-05-04T22:17:39.340Z,1651702659.340 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1131 2022-05-04T22:17:39.343Z,1651702659.343 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000 2022-05-04T22:17:39.343Z,1651702659.343 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000 2022-05-04T22:17:39.343Z,1651702659.343 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000 2022-05-04T22:17:39.343Z,1651702659.343 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000 2022-05-04T22:17:39.344Z,1651702659.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000 2022-05-04T22:17:39.344Z,1651702659.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000 2022-05-04T22:17:39.344Z,1651702659.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000 2022-05-04T22:17:39.344Z,1651702659.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000 2022-05-04T22:17:39.344Z,1651702659.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000 2022-05-04T22:17:39.344Z,1651702659.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000 2022-05-04T22:17:39.345Z,1651702659.345 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000 2022-05-04T22:17:39.345Z,1651702659.345 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000 2022-05-04T22:17:39.376Z,1651702659.376 [CBIT](DEBUG): Deactivating GF circuits. 2022-05-04T22:17:39.376Z,1651702659.376 [CBIT](DEBUG): Deactivating emergency mode. 2022-05-04T22:17:39.412Z,1651702659.412 [CBIT](DEBUG): Backplane powered. 2022-05-04T22:17:39.416Z,1651702659.416 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-05-04T22:17:39.417Z,1651702659.417 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-05-04T22:17:39.417Z,1651702659.417 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-05-04T22:17:39.417Z,1651702659.417 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-05-04T22:17:39.418Z,1651702659.418 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-05-04T22:17:39.418Z,1651702659.418 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing. 2022-05-04T22:17:39.420Z,1651702659.420 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-05-04T22:17:39.420Z,1651702659.420 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-05-04T22:17:39.429Z,1651702659.429 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-05-04T22:17:39.430Z,1651702659.430 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-05-04T22:17:39.431Z,1651702659.431 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-05-04T22:17:39.436Z,1651702659.436 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-05-04T22:17:39.437Z,1651702659.437 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-05-04T22:17:39.473Z,1651702659.473 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-05-04T22:17:39.509Z,1651702659.509 [MissionManager](DEBUG): 2022-05-04T22:17:39.509Z,1651702659.509 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-05-04T22:17:39.605Z,1651702659.605 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-05-04T22:17:39.606Z,1651702659.606 [Default:A.Wait](DEBUG): Construct Wait. 2022-05-04T22:17:39.609Z,1651702659.609 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-05-04T22:17:39.672Z,1651702659.672 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-05-04T22:17:39.675Z,1651702659.675 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-05-04T22:17:39.698Z,1651702659.698 [Default:E.Execute](DEBUG): Construct Execute. 2022-05-04T22:17:39.701Z,1651702659.701 [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 2022-05-04T22:17:39.711Z,1651702659.711 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-05-04T22:17:39.736Z,1651702659.736 [Radio_Surface](INFO): Powering up 2022-05-04T22:17:39.745Z,1651702659.745 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:17:39.785Z,1651702659.785 [Depth_Keller](ERROR): Pressure reading out of range: 1896.040405 decibar 2022-05-04T22:17:39.793Z,1651702659.793 [Power24vConverter](INFO): Powering up. 2022-05-04T22:17:39.813Z,1651702659.813 [DepthRateCalculator](ERROR): Depth measurement is not active 2022-05-04T22:17:39.842Z,1651702659.842 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-05-04T22:17:39.848Z,1651702659.848 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-05-04T22:17:39.849Z,1651702659.849 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-05-04T22:17:39.856Z,1651702659.856 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-05-04T22:17:39.857Z,1651702659.857 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-05-04T22:17:39.864Z,1651702659.864 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-05-04T22:17:39.867Z,1651702659.867 [CBIT](FAULT): Main Battery Failure. Count: 1 2022-05-04T22:17:43.826Z,1651702663.826 [CBIT](FAULT): Main Battery Failure. Count: 11 2022-05-04T22:17:44.580Z,1651702664.580 [WetLabsBB2FL] Communications Fault, FailCount= 1 2022-05-04T22:17:44.580Z,1651702664.580 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:17:44.649Z,1651702664.649 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:17:45.080Z,1651702665.080 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:17:45.452Z,1651702665.452 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-05-04T22:17:45.995Z,1651702665.995 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:17:45.995Z,1651702665.995 [WetLabsBB2FL] No Fault, FailCount= 1 2022-05-04T22:17:46.293Z,1651702666.293 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:17:47.927Z,1651702667.927 [CBIT](FAULT): Main Battery Failure. Count: 21 2022-05-04T22:17:51.584Z,1651702671.584 [WetLabsBB2FL] Communications Fault, FailCount= 2 2022-05-04T22:17:51.584Z,1651702671.584 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:17:51.877Z,1651702671.877 [CBIT](FAULT): Main Battery Failure. Count: 31 2022-05-04T22:17:51.879Z,1651702671.879 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:17:52.084Z,1651702672.084 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:17:53.109Z,1651702673.109 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:17:53.109Z,1651702673.109 [WetLabsBB2FL] No Fault, FailCount= 2 2022-05-04T22:17:53.297Z,1651702673.297 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:17:55.916Z,1651702675.916 [CBIT](FAULT): Main Battery Failure. Count: 41 2022-05-04T22:17:57.109Z,1651702677.109 [DVL_micro](FAULT): Failed to initialize within timeout. 2022-05-04T22:17:57.109Z,1651702677.109 [DVL_micro] Communications Fault, FailCount= 1 2022-05-04T22:17:57.109Z,1651702677.109 [DVL_micro](ERROR): Communications Fault 2022-05-04T22:17:57.164Z,1651702677.164 [CBIT](ERROR): Communications Fault in component: DVL_micro 2022-05-04T22:17:57.580Z,1651702677.580 [DVL_micro](INFO): Powering down 2022-05-04T22:17:58.348Z,1651702678.348 [CBIT](INFO): Clearing failed state for component DVL_micro 2022-05-04T22:17:58.348Z,1651702678.348 [DVL_micro] No Fault, FailCount= 1 2022-05-04T22:17:58.588Z,1651702678.588 [WetLabsBB2FL] Communications Fault, FailCount= 3 2022-05-04T22:17:58.588Z,1651702678.588 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:17:58.773Z,1651702678.773 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:17:59.088Z,1651702679.088 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:17:59.965Z,1651702679.965 [CBIT](FAULT): Main Battery Failure. Count: 51 2022-05-04T22:17:59.967Z,1651702679.967 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:17:59.967Z,1651702679.967 [WetLabsBB2FL] No Fault, FailCount= 3 2022-05-04T22:18:00.309Z,1651702680.309 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:18:03.982Z,1651702683.982 [CBIT](FAULT): Main Battery Failure. Count: 61 2022-05-04T22:18:05.592Z,1651702685.592 [WetLabsBB2FL] Communications Fault, FailCount= 4 2022-05-04T22:18:05.592Z,1651702685.592 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:18:05.600Z,1651702685.600 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:18:06.092Z,1651702686.092 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:18:07.215Z,1651702687.215 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:18:07.215Z,1651702687.215 [WetLabsBB2FL] No Fault, FailCount= 4 2022-05-04T22:18:07.306Z,1651702687.306 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:18:08.026Z,1651702688.026 [CBIT](FAULT): Main Battery Failure. Count: 71 2022-05-04T22:18:08.415Z,1651702688.415 [NAL9602](INFO): Powering up NAL9602 2022-05-04T22:18:12.061Z,1651702692.061 [CBIT](FAULT): Main Battery Failure. Count: 81 2022-05-04T22:18:12.604Z,1651702692.604 [WetLabsBB2FL] Communications Fault, FailCount= 5 2022-05-04T22:18:12.604Z,1651702692.604 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:18:12.896Z,1651702692.896 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:18:12.897Z,1651702692.897 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:18:13.104Z,1651702693.104 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:18:16.089Z,1651702696.089 [DVL_micro](FAULT): Failed to initialize within timeout. 2022-05-04T22:18:16.089Z,1651702696.089 [DVL_micro] Communications Fault, FailCount= 2 2022-05-04T22:18:16.089Z,1651702696.089 [DVL_micro](ERROR): Communications Fault 2022-05-04T22:18:16.102Z,1651702696.102 [CBIT](FAULT): Main Battery Failure. Count: 91 2022-05-04T22:18:16.136Z,1651702696.136 [CBIT](ERROR): Communications Fault in component: DVL_micro 2022-05-04T22:18:16.564Z,1651702696.564 [DVL_micro](INFO): Powering down 2022-05-04T22:18:17.325Z,1651702697.325 [CBIT](INFO): Clearing failed state for component DVL_micro 2022-05-04T22:18:17.325Z,1651702697.325 [DVL_micro] No Fault, FailCount= 2 2022-05-04T22:18:19.322Z,1651702699.322 [NAL9602](INFO): NAL9602 initialized 2022-05-04T22:18:20.160Z,1651702700.160 [CBIT](FAULT): Main Battery Failure. Count: 101 2022-05-04T22:18:24.206Z,1651702704.206 [CBIT](FAULT): Main Battery Failure. Count: 111 2022-05-04T22:18:24.552Z,1651702704.552 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 10 degree 2022-05-04T22:18:24.555Z,1651702704.555 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread. 2022-05-04T22:18:24.595Z,1651702704.595 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-05-04T22:18:28.257Z,1651702708.257 [CBIT](FAULT): Main Battery Failure. Count: 121 2022-05-04T22:18:32.275Z,1651702712.275 [CBIT](FAULT): Main Battery Failure. Count: 131 2022-05-04T22:18:35.079Z,1651702715.079 [DVL_micro](FAULT): Failed to initialize within timeout. 2022-05-04T22:18:35.079Z,1651702715.079 [DVL_micro] Communications Fault, FailCount= 3 2022-05-04T22:18:35.079Z,1651702715.079 [DVL_micro](ERROR): Communications Fault 2022-05-04T22:18:35.095Z,1651702715.095 [CBIT](ERROR): Communications Fault in component: DVL_micro 2022-05-04T22:18:35.095Z,1651702715.095 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2022-05-04T22:18:35.565Z,1651702715.565 [DVL_micro](INFO): Powering down 2022-05-04T22:18:35.587Z,1651702715.587 [CommandExec](FAULT): Scheduling is paused 2022-05-04T22:18:35.588Z,1651702715.588 [CBIT](INFO): Critical error at 20220504T221835 2022-05-04T22:18:35.588Z,1651702715.588 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-05-04T22:18:36.302Z,1651702716.302 [CBIT](FAULT): Main Battery Failure. Count: 141 2022-05-04T22:18:38.739Z,1651702718.739 [SBIT](IMPORTANT): Beginning Startup BIT 2022-05-04T22:18:38.744Z,1651702718.744 [CBIT](IMPORTANT): Beginning ground fault scan 2022-05-04T22:18:39.350Z,1651702719.350 [CommandExec](IMPORTANT): got command maintain clear 2022-05-04T22:18:39.573Z,1651702719.573 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-05-04T22:18:40.353Z,1651702720.353 [CBIT](FAULT): Main Battery Failure. Count: 151 2022-05-04T22:18:44.415Z,1651702724.415 [CBIT](FAULT): Main Battery Failure. Count: 161 2022-05-04T22:18:48.426Z,1651702728.426 [CBIT](FAULT): Main Battery Failure. Count: 171 2022-05-04T22:18:49.670Z,1651702729.670 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.074451 CHAN A1 (24V): -0.163497 CHAN A2 (12V): -0.086861 CHAN A3 (5V): -0.002492 CHAN B0 (3.3V): -0.001417 CHAN B1 (3.15aV): -0.001167 CHAN B2 (3.15bV): -0.001194 CHAN B3 (GND): 0.000152 OPEN: 0.004094 Full Scale: +/- 1 mA 2022-05-04T22:18:52.062Z,1651702732.062 [SBIT](ERROR): Could not read rudderAngleReader_. 2022-05-04T22:18:52.062Z,1651702732.062 [SBIT](ERROR): Could not read massPosReader_. 2022-05-04T22:18:52.473Z,1651702732.473 [CBIT](FAULT): Main Battery Failure. Count: 181 2022-05-04T22:18:56.534Z,1651702736.534 [CBIT](FAULT): Main Battery Failure. Count: 191 2022-05-04T22:18:56.833Z,1651702736.833 [CommandExec](IMPORTANT): got command failComponent 2022-05-04T22:18:56.833Z,1651702736.833 [CommandExec](IMPORTANT): Failed components: 2022-05-04T22:18:56.833Z,1651702736.833 [CommandExec](IMPORTANT): WetLabsBB2FL: Communications Fault 2022-05-04T22:18:56.833Z,1651702736.833 [CommandExec](IMPORTANT): DVL_micro: Communications Fault 2022-05-04T22:19:00.564Z,1651702740.564 [CBIT](FAULT): Main Battery Failure. Count: 201 2022-05-04T22:19:04.612Z,1651702744.612 [CBIT](FAULT): Main Battery Failure. Count: 211 2022-05-04T22:19:08.627Z,1651702748.627 [CBIT](FAULT): Main Battery Failure. Count: 221 2022-05-04T22:19:12.666Z,1651702752.666 [CBIT](FAULT): Main Battery Failure. Count: 231 2022-05-04T22:19:16.715Z,1651702756.715 [CBIT](FAULT): Main Battery Failure. Count: 241 2022-05-04T22:19:18.718Z,1651702758.718 [SBIT](ERROR): Could not read rudderAngleReader_. 2022-05-04T22:19:18.718Z,1651702758.718 [SBIT](ERROR): Could not read massPosReader_. 2022-05-04T22:19:18.718Z,1651702758.718 [SBIT](FAULT): Control surface position failure. 2022-05-04T22:19:20.802Z,1651702760.802 [CBIT](FAULT): Main Battery Failure. Count: 251 2022-05-04T22:19:24.593Z,1651702764.593 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 10 degree 2022-05-04T22:19:24.594Z,1651702764.594 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread. 2022-05-04T22:19:24.788Z,1651702764.788 [CBIT](FAULT): Main Battery Failure. Count: 261 2022-05-04T22:19:24.795Z,1651702764.795 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-05-04T22:19:28.942Z,1651702768.942 [CBIT](FAULT): Main Battery Failure. Count: 271 2022-05-04T22:19:32.458Z,1651702772.458 [SBIT](ERROR): Could not read rudderAngleReader_. 2022-05-04T22:19:32.459Z,1651702772.459 [SBIT](ERROR): Could not read massPosReader_. 2022-05-04T22:19:32.459Z,1651702772.459 [SBIT](FAULT): Control surface position failure. 2022-05-04T22:19:32.874Z,1651702772.874 [SBIT](CRITICAL): SBIT FAILED 2022-05-04T22:19:32.875Z,1651702772.875 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-05-04T22:19:32.875Z,1651702772.875 [SBIT](IMPORTANT): BPC1.loadAtStartup=0 bool; 2022-05-04T22:19:32.876Z,1651702772.876 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool; 2022-05-04T22:19:32.876Z,1651702772.876 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2022-05-04T22:19:32.876Z,1651702772.876 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2022-05-04T22:19:32.876Z,1651702772.876 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2022-05-04T22:19:32.876Z,1651702772.876 [SBIT](IMPORTANT): Express linearApproximation CBIT.ampHoursUsed 1.000000 ampere_hour; 2022-05-04T22:19:32.876Z,1651702772.876 [SBIT](IMPORTANT): MassServo.loadAtStartup=0 bool; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): RudderServo.loadAtStartup=0 bool; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=34.4368 degree; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=34.267 degree; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=34.107 degree; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=34.37 degree; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-120.476 degree; 2022-05-04T22:19:32.877Z,1651702772.877 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-119.333 degree; 2022-05-04T22:19:32.878Z,1651702772.878 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-119.33 degree; 2022-05-04T22:19:32.878Z,1651702772.878 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-120.5 degree; 2022-05-04T22:19:32.879Z,1651702772.879 [CBIT](INFO): Critical error at 20220504T221932 2022-05-04T22:19:32.880Z,1651702772.880 [CBIT](FAULT): Main Battery Failure. Count: 281 2022-05-04T22:19:33.272Z,1651702773.272 [MissionManager](IMPORTANT): Started mission Startup 2022-05-04T22:19:33.273Z,1651702773.273 [Startup] Running Loop=1 2022-05-04T22:19:33.273Z,1651702773.273 [Startup](DEBUG): Aggregate::initialize Startup 2022-05-04T22:19:33.273Z,1651702773.273 [Startup:A.GoToSurface] Running Loop=1 2022-05-04T22:19:33.273Z,1651702773.273 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-05-04T22:19:33.274Z,1651702773.274 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-05-04T22:19:33.274Z,1651702773.274 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-05-04T22:19:33.274Z,1651702773.274 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-05-04T22:19:33.275Z,1651702773.275 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-05-04T22:19:33.275Z,1651702773.275 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-05-04T22:19:33.276Z,1651702773.276 [Startup:StartupSatComms] Running Loop=1 2022-05-04T22:19:33.276Z,1651702773.276 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-05-04T22:19:33.277Z,1651702773.277 [Startup:StartupSatComms:A] Running Loop=1 2022-05-04T22:19:33.671Z,1651702773.671 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-05-04T22:19:36.917Z,1651702776.917 [CBIT](FAULT): Main Battery Failure. Count: 291 2022-05-04T22:19:38.060Z,1651702778.060 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -10 degree 2022-05-04T22:19:40.978Z,1651702780.978 [CBIT](FAULT): Main Battery Failure. Count: 301 2022-05-04T22:19:44.992Z,1651702784.992 [CBIT](FAULT): Main Battery Failure. Count: 311 2022-05-04T22:19:46.556Z,1651702786.556 [CommandExec](IMPORTANT): got command get platform_elevator_angle degree 2022-05-04T22:19:46.556Z,1651702786.556 [CommandExec](IMPORTANT): platform_elevator_angle -10.011745 arcdeg 2022-05-04T22:19:49.023Z,1651702789.023 [CBIT](FAULT): Main Battery Failure. Count: 321 2022-05-04T22:19:53.085Z,1651702793.085 [CBIT](FAULT): Main Battery Failure. Count: 331 2022-05-04T22:19:57.118Z,1651702797.118 [CBIT](FAULT): Main Battery Failure. Count: 341 2022-05-04T22:20:01.125Z,1651702801.125 [AHRS_M2](FAULT): LCB fault: Current Limiter Activated. 2022-05-04T22:20:01.126Z,1651702801.126 [AHRS_M2] Hardware Fault, FailCount= 1 2022-05-04T22:20:01.126Z,1651702801.126 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:20:01.141Z,1651702801.141 [CBIT](FAULT): Main Battery Failure. Count: 351 2022-05-04T22:20:01.143Z,1651702801.143 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:01.526Z,1651702801.526 [AHRS_M2](INFO): Powering down 2022-05-04T22:20:01.933Z,1651702801.933 [AHRS_M2](FAULT): LCB fault: Current Limiter Activated. 2022-05-04T22:20:02.373Z,1651702802.373 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-05-04T22:20:02.373Z,1651702802.373 [AHRS_M2] No Fault, FailCount= 1 2022-05-04T22:20:02.740Z,1651702802.740 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:20:05.181Z,1651702805.181 [CBIT](FAULT): Main Battery Failure. Count: 361 2022-05-04T22:20:07.185Z,1651702807.185 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:07.185Z,1651702807.185 [AHRS_M2] Hardware Fault, FailCount= 2 2022-05-04T22:20:07.185Z,1651702807.185 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:20:07.228Z,1651702807.228 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:07.588Z,1651702807.588 [AHRS_M2](INFO): Powering down 2022-05-04T22:20:07.989Z,1651702807.989 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:08.426Z,1651702808.426 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-05-04T22:20:08.426Z,1651702808.426 [AHRS_M2] No Fault, FailCount= 2 2022-05-04T22:20:08.796Z,1651702808.796 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:20:09.218Z,1651702809.218 [CBIT](FAULT): Main Battery Failure. Count: 371 2022-05-04T22:20:13.246Z,1651702813.246 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:13.246Z,1651702813.246 [AHRS_M2] Hardware Fault, FailCount= 3 2022-05-04T22:20:13.246Z,1651702813.246 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:20:13.272Z,1651702813.272 [CBIT](FAULT): Main Battery Failure. Count: 381 2022-05-04T22:20:13.274Z,1651702813.274 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:13.644Z,1651702813.644 [AHRS_M2](INFO): Powering down 2022-05-04T22:20:14.053Z,1651702814.053 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:14.480Z,1651702814.480 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-05-04T22:20:14.480Z,1651702814.480 [AHRS_M2] No Fault, FailCount= 3 2022-05-04T22:20:14.857Z,1651702814.857 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:20:17.300Z,1651702817.300 [CBIT](FAULT): Main Battery Failure. Count: 391 2022-05-04T22:20:18.511Z,1651702818.511 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 10 degree 2022-05-04T22:20:19.306Z,1651702819.306 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:19.306Z,1651702819.306 [AHRS_M2] Hardware Fault, FailCount= 4 2022-05-04T22:20:19.306Z,1651702819.306 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:20:19.324Z,1651702819.324 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:19.708Z,1651702819.708 [AHRS_M2](INFO): Powering down 2022-05-04T22:20:20.113Z,1651702820.113 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:20.569Z,1651702820.569 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-05-04T22:20:20.569Z,1651702820.569 [AHRS_M2] No Fault, FailCount= 4 2022-05-04T22:20:20.922Z,1651702820.922 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:20:21.374Z,1651702821.374 [CBIT](FAULT): Main Battery Failure. Count: 401 2022-05-04T22:20:25.365Z,1651702825.365 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:25.365Z,1651702825.365 [AHRS_M2] Hardware Fault, FailCount= 5 2022-05-04T22:20:25.365Z,1651702825.365 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:20:25.380Z,1651702825.380 [CBIT](FAULT): Main Battery Failure. Count: 411 2022-05-04T22:20:25.383Z,1651702825.383 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:25.383Z,1651702825.383 [CBIT](CRITICAL): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:25.766Z,1651702825.766 [AHRS_M2](INFO): Powering down 2022-05-04T22:20:25.858Z,1651702825.858 [CBIT](INFO): Critical error at 20220504T222025 2022-05-04T22:20:26.173Z,1651702826.173 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:29.432Z,1651702829.432 [CBIT](FAULT): Main Battery Failure. Count: 421 2022-05-04T22:20:33.477Z,1651702833.477 [Startup:StartupSatComms:A](INFO): Timed out from 2022-05-04T22:19:33.3Z 2022-05-04T22:20:33.478Z,1651702833.478 [Startup:StartupSatComms:A] Stopped 2022-05-04T22:20:33.478Z,1651702833.478 [Startup:StartupSatComms:B] Running Loop=1 2022-05-04T22:20:33.485Z,1651702833.485 [CBIT](FAULT): Main Battery Failure. Count: 431 2022-05-04T22:20:33.870Z,1651702833.870 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-05-04T22:20:37.498Z,1651702837.498 [CBIT](FAULT): Main Battery Failure. Count: 441 2022-05-04T22:20:38.916Z,1651702838.916 [CommandExec](IMPORTANT): got command failComponent 2022-05-04T22:20:38.917Z,1651702838.917 [CommandExec](IMPORTANT): Failed components: 2022-05-04T22:20:38.917Z,1651702838.917 [CommandExec](IMPORTANT): WetLabsBB2FL: Communications Fault 2022-05-04T22:20:38.917Z,1651702838.917 [CommandExec](IMPORTANT): AHRS_M2: Hardware Fault 2022-05-04T22:20:38.917Z,1651702838.917 [CommandExec](IMPORTANT): DVL_micro: Communications Fault 2022-05-04T22:20:39.524Z,1651702839.524 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-05-04T22:20:39.524Z,1651702839.524 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:20:39.534Z,1651702839.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:20:39.941Z,1651702839.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:20:39.941Z,1651702839.941 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-05-04T22:20:41.553Z,1651702841.553 [CBIT](FAULT): Main Battery Failure. Count: 451 2022-05-04T22:20:42.045Z,1651702842.045 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-05-04T22:20:42.045Z,1651702842.045 [AHRS_M2] No Fault, FailCount= 5 2022-05-04T22:20:42.045Z,1651702842.045 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-05-04T22:20:42.328Z,1651702842.328 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:20:42.754Z,1651702842.754 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:20:42.755Z,1651702842.755 [WetLabsBB2FL] No Fault, FailCount= 5 2022-05-04T22:20:42.989Z,1651702842.989 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:20:45.613Z,1651702845.613 [CBIT](FAULT): Main Battery Failure. Count: 461 2022-05-04T22:20:46.770Z,1651702846.770 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:46.771Z,1651702846.771 [AHRS_M2] Hardware Fault, FailCount= 6 2022-05-04T22:20:46.771Z,1651702846.771 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:20:46.788Z,1651702846.788 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:46.788Z,1651702846.788 [CBIT](CRITICAL): Hardware Fault in component: AHRS_M2 2022-05-04T22:20:47.181Z,1651702847.181 [AHRS_M2](INFO): Powering down 2022-05-04T22:20:47.280Z,1651702847.280 [CBIT](INFO): Critical error at 20220504T222046 2022-05-04T22:20:47.586Z,1651702847.586 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:20:48.280Z,1651702848.280 [WetLabsBB2FL] Communications Fault, FailCount= 1 2022-05-04T22:20:48.280Z,1651702848.280 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:20:48.407Z,1651702848.407 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:20:48.780Z,1651702848.780 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:20:49.632Z,1651702849.632 [CBIT](FAULT): Main Battery Failure. Count: 471 2022-05-04T22:20:49.634Z,1651702849.634 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:20:49.634Z,1651702849.634 [WetLabsBB2FL] No Fault, FailCount= 1 2022-05-04T22:20:49.993Z,1651702849.993 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:20:53.659Z,1651702853.659 [CBIT](FAULT): Main Battery Failure. Count: 481 2022-05-04T22:20:55.288Z,1651702855.288 [WetLabsBB2FL] Communications Fault, FailCount= 2 2022-05-04T22:20:55.288Z,1651702855.288 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:20:55.302Z,1651702855.302 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:20:55.788Z,1651702855.788 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:20:56.892Z,1651702856.892 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:20:56.892Z,1651702856.892 [WetLabsBB2FL] No Fault, FailCount= 2 2022-05-04T22:20:57.002Z,1651702857.002 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:20:57.699Z,1651702857.699 [CBIT](FAULT): Main Battery Failure. Count: 491 2022-05-04T22:21:01.749Z,1651702861.749 [CBIT](FAULT): Main Battery Failure. Count: 501 2022-05-04T22:21:02.296Z,1651702862.296 [WetLabsBB2FL] Communications Fault, FailCount= 3 2022-05-04T22:21:02.296Z,1651702862.296 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:21:02.542Z,1651702862.542 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:21:02.796Z,1651702862.796 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:21:03.754Z,1651702863.754 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:21:03.754Z,1651702863.754 [WetLabsBB2FL] No Fault, FailCount= 3 2022-05-04T22:21:04.009Z,1651702864.009 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:21:05.788Z,1651702865.788 [CBIT](FAULT): Main Battery Failure. Count: 511 2022-05-04T22:21:06.284Z,1651702866.284 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-05-04T22:21:06.285Z,1651702866.285 [AHRS_M2] No Fault, FailCount= 6 2022-05-04T22:21:06.285Z,1651702866.285 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-05-04T22:21:06.568Z,1651702866.568 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:21:09.300Z,1651702869.300 [WetLabsBB2FL] Communications Fault, FailCount= 4 2022-05-04T22:21:09.300Z,1651702869.300 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:21:09.419Z,1651702869.419 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:21:09.800Z,1651702869.800 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:21:09.844Z,1651702869.844 [CBIT](FAULT): Main Battery Failure. Count: 521 2022-05-04T22:21:10.651Z,1651702870.651 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:21:10.651Z,1651702870.651 [WetLabsBB2FL] No Fault, FailCount= 4 2022-05-04T22:21:11.010Z,1651702871.010 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:21:11.010Z,1651702871.010 [AHRS_M2] Hardware Fault, FailCount= 7 2022-05-04T22:21:11.010Z,1651702871.010 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:21:11.013Z,1651702871.013 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:21:11.062Z,1651702871.062 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:21:11.062Z,1651702871.062 [CBIT](CRITICAL): Hardware Fault in component: AHRS_M2 2022-05-04T22:21:11.416Z,1651702871.416 [AHRS_M2](INFO): Powering down 2022-05-04T22:21:11.525Z,1651702871.525 [CBIT](INFO): Critical error at 20220504T222111 2022-05-04T22:21:11.825Z,1651702871.825 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:21:13.858Z,1651702873.858 [CBIT](FAULT): Main Battery Failure. Count: 531 2022-05-04T22:21:16.304Z,1651702876.304 [WetLabsBB2FL] Communications Fault, FailCount= 5 2022-05-04T22:21:16.304Z,1651702876.304 [WetLabsBB2FL](ERROR): Communications Fault 2022-05-04T22:21:16.703Z,1651702876.703 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:21:16.703Z,1651702876.703 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2022-05-04T22:21:16.800Z,1651702876.800 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:21:17.900Z,1651702877.900 [CBIT](FAULT): Main Battery Failure. Count: 541 2022-05-04T22:21:21.955Z,1651702881.955 [CBIT](FAULT): Main Battery Failure. Count: 551 2022-05-04T22:21:26.011Z,1651702886.011 [CBIT](FAULT): Main Battery Failure. Count: 561 2022-05-04T22:21:29.088Z,1651702889.088 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-05-04T22:21:29.088Z,1651702889.088 [AHRS_M2] No Fault, FailCount= 7 2022-05-04T22:21:29.089Z,1651702889.089 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-05-04T22:21:29.192Z,1651702889.192 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:21:30.023Z,1651702890.023 [CBIT](FAULT): Main Battery Failure. Count: 571 2022-05-04T22:21:33.634Z,1651702893.634 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:21:33.634Z,1651702893.634 [AHRS_M2] Hardware Fault, FailCount= 8 2022-05-04T22:21:33.635Z,1651702893.635 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:21:33.643Z,1651702893.643 [Startup:StartupSatComms:B](INFO): Timed out from 2022-05-04T22:20:33.5Z 2022-05-04T22:21:33.644Z,1651702893.644 [Startup:StartupSatComms:B] Stopped 2022-05-04T22:21:33.644Z,1651702893.644 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-05-04T22:21:33.644Z,1651702893.644 [Startup:StartupSatComms] Stopped 2022-05-04T22:21:33.644Z,1651702893.644 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-05-04T22:21:33.645Z,1651702893.645 [Startup](INFO): Completed Startup 2022-05-04T22:21:33.645Z,1651702893.645 [MissionManager](INFO): Startup is completed. 2022-05-04T22:21:33.645Z,1651702893.645 [MissionManager](INFO): Uninitializing Mission Startup 2022-05-04T22:21:33.645Z,1651702893.645 [Startup] Stopped 2022-05-04T22:21:33.645Z,1651702893.645 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-05-04T22:21:33.645Z,1651702893.645 [Startup:A.GoToSurface] Stopped 2022-05-04T22:21:33.645Z,1651702893.645 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-05-04T22:21:33.683Z,1651702893.683 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:21:33.684Z,1651702893.684 [CBIT](CRITICAL): Hardware Fault in component: AHRS_M2 2022-05-04T22:21:34.040Z,1651702894.040 [AHRS_M2](INFO): Powering down 2022-05-04T22:21:34.128Z,1651702894.128 [MissionManager](IMPORTANT): Started mission Default 2022-05-04T22:21:34.128Z,1651702894.128 [Default] Running Loop=1 2022-05-04T22:21:34.128Z,1651702894.128 [Default](DEBUG): Aggregate::initialize Default 2022-05-04T22:21:34.128Z,1651702894.128 [Default:B.GoToSurface] Running Loop=1 2022-05-04T22:21:34.128Z,1651702894.128 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-05-04T22:21:34.129Z,1651702894.129 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-05-04T22:21:34.129Z,1651702894.129 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-05-04T22:21:34.129Z,1651702894.129 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-05-04T22:21:34.130Z,1651702894.130 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-05-04T22:21:34.130Z,1651702894.130 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-05-04T22:21:34.130Z,1651702894.130 [Default:A.Wait] Running Loop=1 2022-05-04T22:21:34.130Z,1651702894.130 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-05-04T22:21:34.138Z,1651702894.138 [CBIT](INFO): Critical error at 20220504T222133 2022-05-04T22:21:34.138Z,1651702894.138 [CBIT](FAULT): Main Battery Failure. Count: 581 2022-05-04T22:21:34.445Z,1651702894.445 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:21:35.284Z,1651702895.284 [CBIT](INFO): Clearing failed state for component DVL_micro 2022-05-04T22:21:35.284Z,1651702895.284 [DVL_micro] No Fault, FailCount= 3 2022-05-04T22:21:38.102Z,1651702898.102 [CBIT](FAULT): Main Battery Failure. Count: 591 2022-05-04T22:21:40.856Z,1651702900.856 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-05-04T22:21:40.856Z,1651702900.856 [AHRS_M2] No Fault, FailCount= 8 2022-05-04T22:21:40.857Z,1651702900.857 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-05-04T22:21:40.919Z,1651702900.919 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:21:42.156Z,1651702902.156 [CBIT](FAULT): Main Battery Failure. Count: 601 2022-05-04T22:21:45.357Z,1651702905.357 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:21:45.357Z,1651702905.357 [AHRS_M2] Hardware Fault, FailCount= 9 2022-05-04T22:21:45.357Z,1651702905.357 [AHRS_M2](ERROR): Hardware Fault 2022-05-04T22:21:45.399Z,1651702905.399 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2022-05-04T22:21:45.399Z,1651702905.399 [CBIT](CRITICAL): Hardware Fault in component: AHRS_M2 2022-05-04T22:21:45.756Z,1651702905.756 [AHRS_M2](INFO): Powering down 2022-05-04T22:21:45.855Z,1651702905.855 [CBIT](INFO): Critical error at 20220504T222145 2022-05-04T22:21:46.165Z,1651702906.165 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-05-04T22:21:46.180Z,1651702906.180 [CBIT](FAULT): Main Battery Failure. Count: 611 2022-05-04T22:21:47.398Z,1651702907.398 [Default:A.Wait](INFO): Done Waiting. 2022-05-04T22:21:47.399Z,1651702907.399 [Default:A.Wait] Stopped 2022-05-04T22:21:47.399Z,1651702907.399 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-05-04T22:21:47.810Z,1651702907.810 [Default:CheckIn] Running Loop=1 2022-05-04T22:21:47.810Z,1651702907.810 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-05-04T22:21:47.810Z,1651702907.810 [Default:CheckIn:Read_GPS] Running Loop=1 2022-05-04T22:21:48.206Z,1651702908.206 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-05-04T22:21:50.226Z,1651702910.226 [CBIT](FAULT): Main Battery Failure. Count: 621 2022-05-04T22:21:53.030Z,1651702913.030 [DVL_micro](FAULT): Failed to initialize within timeout. 2022-05-04T22:21:53.030Z,1651702913.030 [DVL_micro] Communications Fault, FailCount= 1 2022-05-04T22:21:53.030Z,1651702913.030 [DVL_micro](ERROR): Communications Fault 2022-05-04T22:21:53.058Z,1651702913.058 [CBIT](ERROR): Communications Fault in component: DVL_micro 2022-05-04T22:21:53.508Z,1651702913.508 [DVL_micro](INFO): Powering down 2022-05-04T22:21:54.259Z,1651702914.259 [CBIT](FAULT): Main Battery Failure. Count: 631 2022-05-04T22:21:54.261Z,1651702914.261 [CBIT](INFO): Clearing failed state for component DVL_micro 2022-05-04T22:21:54.261Z,1651702914.261 [DVL_micro] No Fault, FailCount= 1 2022-05-04T22:21:58.313Z,1651702918.313 [CBIT](FAULT): Main Battery Failure. Count: 641 2022-05-04T22:22:00.636Z,1651702920.636 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-05-04T22:22:00.636Z,1651702920.636 [AHRS_M2] No Fault, FailCount= 9 2022-05-04T22:22:00.636Z,1651702920.636 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-05-04T22:22:00.708Z,1651702920.708 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-04T22:22:02.349Z,1651702922.349 [CBIT](FAULT): Main Battery Failure. Count: 651 2022-05-04T22:22:06.388Z,1651702926.388 [CBIT](FAULT): Main Battery Failure. Count: 661 2022-05-04T22:22:10.426Z,1651702930.426 [CBIT](FAULT): Main Battery Failure. Count: 671 2022-05-04T22:22:12.022Z,1651702932.022 [DVL_micro](FAULT): Failed to initialize within timeout. 2022-05-04T22:22:12.022Z,1651702932.022 [DVL_micro] Communications Fault, FailCount= 2 2022-05-04T22:22:12.022Z,1651702932.022 [DVL_micro](ERROR): Communications Fault 2022-05-04T22:22:12.039Z,1651702932.039 [CBIT](ERROR): Communications Fault in component: DVL_micro 2022-05-04T22:22:12.508Z,1651702932.508 [DVL_micro](INFO): Powering down 2022-05-04T22:22:13.285Z,1651702933.285 [CBIT](INFO): Clearing failed state for component DVL_micro 2022-05-04T22:22:13.285Z,1651702933.285 [DVL_micro] No Fault, FailCount= 2 2022-05-04T22:22:14.488Z,1651702934.488 [CBIT](FAULT): Main Battery Failure. Count: 681 2022-05-04T22:22:18.535Z,1651702938.535 [CBIT](FAULT): Main Battery Failure. Count: 691 2022-05-04T22:22:22.548Z,1651702942.548 [CBIT](FAULT): Main Battery Failure. Count: 701 2022-05-04T22:22:26.583Z,1651702946.583 [CBIT](FAULT): Main Battery Failure. Count: 711 2022-05-04T22:22:27.541Z,1651702947.541 [CommandExec](IMPORTANT): got command get platform_orientation degree 2022-05-04T22:22:27.541Z,1651702947.541 [CommandExec](IMPORTANT): platform_orientation 59.249862 arcdeg 2022-05-04T22:22:29.697Z,1651702949.697 [CommandExec](IMPORTANT): got command get platform_orientation degree 2022-05-04T22:22:29.698Z,1651702949.698 [CommandExec](IMPORTANT): platform_orientation 59.252499 arcdeg 2022-05-04T22:22:30.630Z,1651702950.630 [CBIT](FAULT): Main Battery Failure. Count: 721 2022-05-04T22:22:31.017Z,1651702951.017 [DVL_micro](FAULT): Failed to initialize within timeout. 2022-05-04T22:22:31.017Z,1651702951.017 [DVL_micro] Communications Fault, FailCount= 3 2022-05-04T22:22:31.017Z,1651702951.017 [DVL_micro](ERROR): Communications Fault 2022-05-04T22:22:31.061Z,1651702951.061 [CBIT](ERROR): Communications Fault in component: DVL_micro 2022-05-04T22:22:31.061Z,1651702951.061 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2022-05-04T22:22:31.492Z,1651702951.492 [DVL_micro](INFO): Powering down 2022-05-04T22:22:31.516Z,1651702951.516 [CBIT](INFO): Critical error at 20220504T222231 2022-05-04T22:22:34.688Z,1651702954.688 [CBIT](FAULT): Main Battery Failure. Count: 731 2022-05-04T22:22:38.703Z,1651702958.703 [CBIT](FAULT): Main Battery Failure. Count: 741 2022-05-04T22:22:42.753Z,1651702962.753 [CBIT](FAULT): Main Battery Failure. Count: 751 2022-05-04T22:22:59.931Z,1651702979.931 [CommandExec](IMPORTANT): got command get platform_orientation degree 2022-05-04T22:22:59.932Z,1651702979.932 [CommandExec](IMPORTANT): platform_orientation 59.226107 arcdeg 2022-05-04T22:23:23.130Z,1651703003.130 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-05-04T22:23:40.529Z,1651703020.529 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-05-04T22:23:40.529Z,1651703020.529 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:23:40.561Z,1651703020.561 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:23:40.935Z,1651703020.935 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:23:40.935Z,1651703020.935 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-05-04T22:23:46.611Z,1651703026.611 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2022-05-04T22:23:46.611Z,1651703026.611 [WetLabsBB2FL] No Fault, FailCount= 5 2022-05-04T22:23:46.697Z,1651703026.697 [WetLabsBB2FL](INFO): Powering up 2022-05-04T22:23:48.808Z,1651703028.808 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:24:07.553Z,1651703047.553 [CommandExec](IMPORTANT): got command strobe off 2022-05-04T22:24:07.553Z,1651703047.553 [CommandExec](IMPORTANT): Deactivating strobe 2022-05-04T22:25:31.267Z,1651703131.267 [CBIT](INFO): Clearing failed state for component DVL_micro 2022-05-04T22:25:31.267Z,1651703131.267 [DVL_micro] No Fault, FailCount= 3 2022-05-04T22:25:33.462Z,1651703133.462 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004967 2022-05-04T22:25:46.571Z,1651703146.571 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro 2022-05-04T22:26:35.068Z,1651703195.068 [DVL_micro](ERROR): only read 2 of 4 data items 2022-05-04T22:26:35.068Z,1651703195.068 [DVL_micro](ERROR): Failed to parse: :BI,+00042,-00108+00000,I 2022-05-04T22:26:41.527Z,1651703201.527 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-05-04T22:26:41.527Z,1651703201.527 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:26:41.537Z,1651703201.537 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:26:41.949Z,1651703201.949 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:26:41.949Z,1651703201.949 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-05-04T22:26:47.983Z,1651703207.983 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-04T22:21:47.8Z 2022-05-04T22:26:47.983Z,1651703207.983 [Default:CheckIn:Read_GPS] Stopped 2022-05-04T22:26:47.983Z,1651703207.983 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-05-04T22:26:48.410Z,1651703208.410 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-05-04T22:26:55.423Z,1651703215.423 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20220504T203335/Courier0034.lzma 2022-05-04T22:26:56.426Z,1651703216.426 [DataOverHttps](INFO): Moved sent file to Logs/20220504T203335/Courier0034.lzma.bak 2022-05-04T22:26:56.426Z,1651703216.426 [DataOverHttps](INFO): SBD MOMSN=16689755 2022-05-04T22:27:11.972Z,1651703231.972 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20220504T221729/Courier0000.lzma 2022-05-04T22:27:12.974Z,1651703232.974 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Courier0000.lzma.bak 2022-05-04T22:27:12.974Z,1651703232.974 [DataOverHttps](INFO): SBD MOMSN=16689758 2022-05-04T22:27:28.527Z,1651703248.527 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220504T221729/Courier0004.lzma 2022-05-04T22:27:29.530Z,1651703249.530 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Courier0004.lzma.bak 2022-05-04T22:27:29.530Z,1651703249.530 [DataOverHttps](INFO): SBD MOMSN=16689764 2022-05-04T22:27:45.146Z,1651703265.146 [DataOverHttps](INFO): Sending 1470 bytes from file Logs/20220504T203335/Express0035.lzma 2022-05-04T22:27:46.146Z,1651703266.146 [DataOverHttps](INFO): Moved sent file to Logs/20220504T203335/Express0035.lzma.bak 2022-05-04T22:27:46.146Z,1651703266.146 [DataOverHttps](INFO): SBD MOMSN=16689771 2022-05-04T22:28:02.010Z,1651703282.010 [DataOverHttps](INFO): Sending 1588 bytes from file Logs/20220504T221729/Express0001.lzma 2022-05-04T22:28:03.010Z,1651703283.010 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Express0001.lzma.bak 2022-05-04T22:28:03.010Z,1651703283.010 [DataOverHttps](INFO): SBD MOMSN=16689889 2022-05-04T22:28:18.553Z,1651703298.553 [DataOverHttps](INFO): Sending 823 bytes from file Logs/20220504T221729/Express0005.lzma 2022-05-04T22:28:19.554Z,1651703299.554 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Express0005.lzma.bak 2022-05-04T22:28:19.554Z,1651703299.554 [DataOverHttps](INFO): SBD MOMSN=16689988 2022-05-04T22:28:20.486Z,1651703300.486 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-05-04T22:28:20.486Z,1651703300.486 [NAL9602] Data Fault, FailCount= 1 2022-05-04T22:28:20.486Z,1651703300.486 [NAL9602](ERROR): Data Fault 2022-05-04T22:28:20.502Z,1651703300.502 [CBIT](ERROR): Data Fault in component: NAL9602 2022-05-04T22:28:20.917Z,1651703300.917 [NAL9602](INFO): Powering down 2022-05-04T22:28:20.943Z,1651703300.943 [Default:CheckIn:Read_Iridium] Stopped 2022-05-04T22:28:20.943Z,1651703300.943 [Default:CheckIn:C.Wait] Running Loop=1 2022-05-04T22:28:20.943Z,1651703300.943 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-05-04T22:28:21.732Z,1651703301.732 [CBIT](INFO): Clearing failed state for component NAL9602 2022-05-04T22:28:21.732Z,1651703301.732 [NAL9602] No Fault, FailCount= 1 2022-05-04T22:28:51.200Z,1651703331.200 [NAL9602](INFO): Powering up NAL9602 2022-05-04T22:29:02.121Z,1651703342.121 [NAL9602](INFO): NAL9602 initialized 2022-05-04T22:29:42.520Z,1651703382.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2022-05-04T22:29:42.520Z,1651703382.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:29:42.530Z,1651703382.530 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:29:42.981Z,1651703382.981 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:29:42.981Z,1651703382.981 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2022-05-04T22:30:43.929Z,1651703443.929 [DVL_micro](ERROR): only read 3 of 4 data items 2022-05-04T22:30:43.929Z,1651703443.929 [DVL_micro](ERROR): Failed to parse: :BI,+0078,-00099,+00000,I 2022-05-04T22:31:24.330Z,1651703484.330 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-05-04T22:31:24.330Z,1651703484.330 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+22.4,0000.,000 2022-05-04T22:32:43.544Z,1651703563.544 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2022-05-04T22:32:43.544Z,1651703563.544 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:32:43.578Z,1651703563.578 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:32:43.957Z,1651703563.957 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:32:43.957Z,1651703563.957 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2022-05-04T22:32:47.985Z,1651703567.985 [DVL_micro](ERROR): Failed to parse: 00000000022.5,00000,000 2022-05-04T22:33:21.542Z,1651703601.542 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-05-04T22:33:21.542Z,1651703601.542 [Default:CheckIn:C.Wait] Stopped 2022-05-04T22:33:21.542Z,1651703601.542 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-05-04T22:33:21.542Z,1651703601.542 [Default:CheckIn:D] Running Loop=1 2022-05-04T22:33:21.949Z,1651703601.949 [Default:CheckIn:D] Stopped 2022-05-04T22:33:21.950Z,1651703601.950 [Default:CheckIn:E] Running Loop=1 2022-05-04T22:33:22.330Z,1651703602.330 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.797020 min 2022-05-04T22:33:22.330Z,1651703602.330 [Default:CheckIn:E] Stopped 2022-05-04T22:33:22.330Z,1651703602.330 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-05-04T22:33:22.330Z,1651703602.330 [Default:CheckIn] Stopped 2022-05-04T22:33:22.330Z,1651703602.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-05-04T22:33:22.330Z,1651703602.330 [Default:CheckIn](INFO): Running loop #2 2022-05-04T22:33:22.331Z,1651703602.331 [Default:CheckIn] Running Loop=2 2022-05-04T22:33:22.331Z,1651703602.331 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-05-04T22:33:22.331Z,1651703602.331 [Default:CheckIn:Read_GPS] Running Loop=1 2022-05-04T22:35:44.586Z,1651703744.586 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2022-05-04T22:35:44.586Z,1651703744.586 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:35:44.596Z,1651703744.596 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:35:45.021Z,1651703745.021 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:35:45.021Z,1651703745.021 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2022-05-04T22:36:20.535Z,1651703780.535 [DVL_micro](ERROR): Failed to parse: :WI,+00068,-00025,-00089,+00000,A 2022-05-04T22:38:22.586Z,1651703902.586 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-04T22:33:22.3Z 2022-05-04T22:38:22.586Z,1651703902.586 [Default:CheckIn:Read_GPS] Stopped 2022-05-04T22:38:22.586Z,1651703902.586 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-05-04T22:38:29.979Z,1651703909.979 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220504T221729/Courier0007.lzma 2022-05-04T22:38:30.982Z,1651703910.982 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Courier0007.lzma.bak 2022-05-04T22:38:30.982Z,1651703910.982 [DataOverHttps](INFO): SBD MOMSN=16690059 2022-05-04T22:38:45.605Z,1651703925.605 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2022-05-04T22:38:45.605Z,1651703925.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:38:45.657Z,1651703925.657 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:38:46.030Z,1651703926.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:38:46.030Z,1651703926.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2022-05-04T22:38:46.728Z,1651703926.728 [DataOverHttps](INFO): Sending 298 bytes from file Logs/20220504T221729/Express0008.lzma 2022-05-04T22:38:47.730Z,1651703927.730 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Express0008.lzma.bak 2022-05-04T22:38:47.730Z,1651703927.730 [DataOverHttps](INFO): SBD MOMSN=16690061 2022-05-04T22:38:49.257Z,1651703929.257 [Default:CheckIn:Read_Iridium] Stopped 2022-05-04T22:38:49.258Z,1651703929.258 [Default:CheckIn:C.Wait] Running Loop=1 2022-05-04T22:38:49.258Z,1651703929.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-05-04T22:39:04.984Z,1651703944.984 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-05-04T22:39:04.984Z,1651703944.984 [NAL9602] Data Fault, FailCount= 2 2022-05-04T22:39:04.984Z,1651703944.984 [NAL9602](ERROR): Data Fault 2022-05-04T22:39:05.001Z,1651703945.001 [CBIT](ERROR): Data Fault in component: NAL9602 2022-05-04T22:39:05.406Z,1651703945.406 [NAL9602](INFO): Powering down 2022-05-04T22:39:06.199Z,1651703946.199 [CBIT](INFO): Clearing failed state for component NAL9602 2022-05-04T22:39:06.199Z,1651703946.199 [NAL9602] No Fault, FailCount= 2 2022-05-04T22:39:35.686Z,1651703975.686 [NAL9602](INFO): Powering up NAL9602 2022-05-04T22:39:46.590Z,1651703986.590 [NAL9602](INFO): NAL9602 initialized 2022-05-04T22:41:18.829Z,1651704078.829 [CommandExec](IMPORTANT): got command report 2022-05-04T22:41:24.757Z,1651704084.757 [CommandExec](IMPORTANT): got command show variable ahU 2022-05-04T22:41:29.277Z,1651704089.277 [CommandExec](IMPORTANT): got command show variable amph 2022-05-04T22:41:29.404Z,1651704089.404 [CommandExec](IMPORTANT): CBIT.ampHoursUsed (ampere_hour) 2022-05-04T22:41:36.730Z,1651704096.730 [CommandExec](IMPORTANT): got command get CBIT.ampHoursUsed 2022-05-04T22:41:36.730Z,1651704096.730 [CommandExec](IMPORTANT): CBIT.ampHoursUsed 153.766418 Ah 2022-05-04T22:41:46.618Z,1651704106.618 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2022-05-04T22:41:46.618Z,1651704106.618 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:41:46.628Z,1651704106.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:41:47.068Z,1651704107.068 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:41:47.068Z,1651704107.068 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2022-05-04T22:43:49.813Z,1651704229.813 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-05-04T22:43:49.813Z,1651704229.813 [Default:CheckIn:C.Wait] Stopped 2022-05-04T22:43:49.813Z,1651704229.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-05-04T22:43:49.813Z,1651704229.813 [Default:CheckIn:D] Running Loop=1 2022-05-04T22:43:50.221Z,1651704230.221 [Default:CheckIn:D] Stopped 2022-05-04T22:43:50.221Z,1651704230.221 [Default:CheckIn:E] Running Loop=1 2022-05-04T22:43:50.650Z,1651704230.650 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.268209 min 2022-05-04T22:43:50.650Z,1651704230.650 [Default:CheckIn:E] Stopped 2022-05-04T22:43:50.651Z,1651704230.651 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-05-04T22:43:50.651Z,1651704230.651 [Default:CheckIn] Stopped 2022-05-04T22:43:50.651Z,1651704230.651 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-05-04T22:43:50.651Z,1651704230.651 [Default:CheckIn](INFO): Running loop #3 2022-05-04T22:43:50.651Z,1651704230.651 [Default:CheckIn] Running Loop=3 2022-05-04T22:43:50.651Z,1651704230.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-05-04T22:43:50.651Z,1651704230.651 [Default:CheckIn:Read_GPS] Running Loop=1 2022-05-04T22:43:55.875Z,1651704235.875 [DVL_micro](ERROR): only read 3 of 4 data items 2022-05-04T22:43:55.895Z,1651704235.895 [DVL_micro](ERROR): Failed to parse: :RD,+9999.999,+9999.99,+9999.99 2022-05-04T22:44:47.633Z,1651704287.633 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2022-05-04T22:44:47.633Z,1651704287.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:44:47.643Z,1651704287.643 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:44:48.038Z,1651704288.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:44:48.038Z,1651704288.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2022-05-04T22:45:01.747Z,1651704301.747 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-05-04T22:45:01.747Z,1651704301.747 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+220,1489.0,000 2022-05-04T22:47:48.625Z,1651704468.625 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2022-05-04T22:47:48.625Z,1651704468.625 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:47:48.635Z,1651704468.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:47:49.041Z,1651704469.041 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:47:49.041Z,1651704469.041 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2022-05-04T22:47:55.876Z,1651704475.876 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-05-04T22:47:55.876Z,1651704475.876 [DVL_micro](ERROR): Failed to parse: :TS,000000,35.0,+220,1489.0,0+00021,-00002,-00085,+00000,A 2022-05-04T22:48:50.890Z,1651704530.890 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-04T22:43:50.7Z 2022-05-04T22:48:50.890Z,1651704530.890 [Default:CheckIn:Read_GPS] Stopped 2022-05-04T22:48:50.890Z,1651704530.890 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-05-04T22:48:58.178Z,1651704538.178 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220504T221729/Courier0010.lzma 2022-05-04T22:48:59.162Z,1651704539.162 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Courier0010.lzma.bak 2022-05-04T22:48:59.162Z,1651704539.162 [DataOverHttps](INFO): SBD MOMSN=16690065 2022-05-04T22:49:13.768Z,1651704553.768 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20220504T221729/Express0011.lzma 2022-05-04T22:49:14.770Z,1651704554.770 [DataOverHttps](INFO): Moved sent file to Logs/20220504T221729/Express0011.lzma.bak 2022-05-04T22:49:14.770Z,1651704554.770 [DataOverHttps](INFO): SBD MOMSN=16690067 2022-05-04T22:49:16.325Z,1651704556.325 [Default:CheckIn:Read_Iridium] Stopped 2022-05-04T22:49:16.325Z,1651704556.325 [Default:CheckIn:C.Wait] Running Loop=1 2022-05-04T22:49:16.325Z,1651704556.325 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-05-04T22:49:49.415Z,1651704589.415 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-05-04T22:49:49.415Z,1651704589.415 [NAL9602] Data Fault, FailCount= 3 2022-05-04T22:49:49.415Z,1651704589.415 [NAL9602](ERROR): Data Fault 2022-05-04T22:49:49.494Z,1651704589.494 [CBIT](ERROR): Data Fault in component: NAL9602 2022-05-04T22:49:49.825Z,1651704589.825 [NAL9602](INFO): Powering down 2022-05-04T22:49:50.670Z,1651704590.670 [CBIT](INFO): Clearing failed state for component NAL9602 2022-05-04T22:49:50.670Z,1651704590.670 [NAL9602] No Fault, FailCount= 3 2022-05-04T22:50:20.124Z,1651704620.124 [NAL9602](INFO): Powering up NAL9602 2022-05-04T22:50:31.022Z,1651704631.022 [NAL9602](INFO): NAL9602 initialized 2022-05-04T22:50:45.576Z,1651704645.576 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-05-04T22:50:45.576Z,1651704645.576 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35,0000.0,1489.0,000 2022-05-04T22:50:49.623Z,1651704649.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2022-05-04T22:50:49.623Z,1651704649.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-04T22:50:49.634Z,1651704649.634 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-04T22:50:50.074Z,1651704650.074 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-04T22:50:50.074Z,1651704650.074 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2022-05-04T22:52:06.379Z,1651704726.379 [DVL_micro](ERROR): only read 2 of 4 data items 2022-05-04T22:52:06.379Z,1651704726.379 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,,+9999.99 2022-05-04T22:53:32.968Z,1651704812.968 [CommandExec](IMPORTANT): got command quit 2022-05-04T22:53:33.972Z,1651704813.972 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:33.972Z,1651704813.972 [CommandExec](INFO): Uninitializing the command executive. 2022-05-04T22:53:33.972Z,1651704813.972 [CommandExec](INFO): Uninitializing the command scheduler. 2022-05-04T22:53:33.972Z,1651704813.972 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:33.988Z,1651704813.988 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-05-04T22:53:33.988Z,1651704813.988 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-05-04T22:53:33.988Z,1651704813.988 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:33.989Z,1651704813.989 [NavChartDb](INFO): Join timeout helper Thread ID is 1184 2022-05-04T22:53:34.172Z,1651704814.172 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:34.172Z,1651704814.172 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:34.176Z,1651704814.176 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-05-04T22:53:34.176Z,1651704814.176 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:34.176Z,1651704814.176 [Radio_Surface](INFO): Join timeout helper Thread ID is 1185 2022-05-04T22:53:34.540Z,1651704814.540 [Radio_Surface](INFO): Powering down 2022-05-04T22:53:34.541Z,1651704814.541 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:34.541Z,1651704814.541 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:34.556Z,1651704814.556 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-05-04T22:53:34.556Z,1651704814.556 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:34.556Z,1651704814.556 [Onboard](INFO): Join timeout helper Thread ID is 1186 2022-05-04T22:53:34.900Z,1651704814.900 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-05-04T22:53:36.097Z,1651704816.097 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:36.098Z,1651704816.098 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:36.108Z,1651704816.108 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-05-04T22:53:36.108Z,1651704816.108 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:36.108Z,1651704816.108 [DataOverHttps](INFO): Join timeout helper Thread ID is 1187 2022-05-04T22:53:36.908Z,1651704816.908 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:36.908Z,1651704816.908 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:36.920Z,1651704816.920 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-05-04T22:53:36.920Z,1651704816.920 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:36.920Z,1651704816.920 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1188 2022-05-04T22:53:36.992Z,1651704816.992 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:36.992Z,1651704816.992 [WetLabsBB2FL](INFO): Powering down 2022-05-04T22:53:36.993Z,1651704816.993 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.009Z,1651704817.009 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-05-04T22:53:37.009Z,1651704817.009 [logger ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.009Z,1651704817.009 [logger](INFO): Join timeout helper Thread ID is 1189 2022-05-04T22:53:37.020Z,1651704817.020 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:37.020Z,1651704817.020 [logger ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.028Z,1651704817.028 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-05-04T22:53:37.029Z,1651704817.029 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.029Z,1651704817.029 [CommandLine](INFO): Join timeout helper Thread ID is 1190 2022-05-04T22:53:37.072Z,1651704817.072 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:37.072Z,1651704817.072 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.088Z,1651704817.088 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-05-04T22:53:37.089Z,1651704817.089 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.089Z,1651704817.089 [CommandExec](INFO): Join timeout helper Thread ID is 1191 2022-05-04T22:53:37.090Z,1651704817.090 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-05-04T22:53:37.090Z,1651704817.090 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.091Z,1651704817.091 [controlThread](INFO): Join timeout helper Thread ID is 1192 2022-05-04T22:53:37.268Z,1651704817.268 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-04T22:53:37.268Z,1651704817.268 [controlThread](DEBUG): Uninitializing ControlThread 2022-05-04T22:53:37.269Z,1651704817.269 [AHRS_M2](INFO): Powering down 2022-05-04T22:53:37.412Z,1651704817.412 [DVL_micro](INFO): Powering down 2022-05-04T22:53:37.413Z,1651704817.413 [NAL9602](INFO): Powering down 2022-05-04T22:53:37.414Z,1651704817.414 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-05-04T22:53:37.415Z,1651704817.415 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-05-04T22:53:37.416Z,1651704817.416 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-05-04T22:53:37.417Z,1651704817.417 [MissionManager](INFO): Uninitializing Mission Default 2022-05-04T22:53:37.417Z,1651704817.417 [Default] Stopped 2022-05-04T22:53:37.417Z,1651704817.417 [Default](DEBUG): Aggregate::uninitialize Default 2022-05-04T22:53:37.417Z,1651704817.417 [Default:B.GoToSurface] Stopped 2022-05-04T22:53:37.417Z,1651704817.417 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-05-04T22:53:37.417Z,1651704817.417 [Default:CheckIn] Stopped 2022-05-04T22:53:37.417Z,1651704817.417 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-05-04T22:53:37.417Z,1651704817.417 [Default:CheckIn:C.Wait] Stopped 2022-05-04T22:53:37.417Z,1651704817.417 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-05-04T22:53:37.420Z,1651704817.420 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-05-04T22:53:37.420Z,1651704817.420 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-05-04T22:53:37.421Z,1651704817.421 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-05-04T22:53:37.421Z,1651704817.421 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-05-04T22:53:37.421Z,1651704817.421 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-05-04T22:53:37.422Z,1651704817.422 [BuoyancyServo](INFO): Powering down 2022-05-04T22:53:37.436Z,1651704817.436 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-05-04T22:53:37.436Z,1651704817.436 [ElevatorServo](INFO): Powering down 2022-05-04T22:53:37.437Z,1651704817.437 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2022-05-04T22:53:37.437Z,1651704817.437 [ThrusterHE](INFO): Powering down 2022-05-04T22:53:37.438Z,1651704817.438 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-05-04T22:53:37.438Z,1651704817.438 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-05-04T22:53:37.439Z,1651704817.439 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-05-04T22:53:37.439Z,1651704817.439 [CBIT](DEBUG): Powering off loads. 2022-05-04T22:53:37.452Z,1651704817.452 [CBIT](DEBUG): Disabling WDT. 2022-05-04T22:53:37.464Z,1651704817.464 [CBIT](DEBUG): Opening all GF detection circuits. 2022-05-04T22:53:37.464Z,1651704817.464 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.495Z,1651704817.495 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.526Z,1651704817.526 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.528Z,1651704817.528 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.535Z,1651704817.535 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.586Z,1651704817.586 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-05-04T22:53:37.647Z,1651704817.647 [logger ThreadHandler](INFO): Thread cancelled.