2019-07-26T22:33:20.327Z,1564180400.327 [Supervisor](DEBUG): Initializing supervisor. 2019-07-26T22:33:20.331Z,1564180400.331 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-07-26T22:33:20.331Z,1564180400.331 [SyncHandler](INFO): Protected caller Thread ID is 5738 2019-07-26T22:33:20.331Z,1564180400.331 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-07-26T22:33:20.332Z,1564180400.332 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-07-26T22:33:20.333Z,1564180400.333 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5739 2019-07-26T22:33:20.336Z,1564180400.336 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-07-26T22:33:20.348Z,1564180400.348 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-07-26T22:33:20.349Z,1564180400.349 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-07-26T22:33:20.350Z,1564180400.350 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5740 2019-07-26T22:33:20.351Z,1564180400.351 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-07-26T22:33:20.352Z,1564180400.352 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-07-26T22:33:20.352Z,1564180400.352 [logger ThreadHandler](INFO): Protected caller Thread ID is 5741 2019-07-26T22:33:20.354Z,1564180400.354 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-07-26T22:33:20.354Z,1564180400.354 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-07-26T22:33:20.356Z,1564180400.356 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-07-26T22:33:20.518Z,1564180400.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-07-26T22:33:20.519Z,1564180400.519 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-07-26T22:33:20.724Z,1564180400.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-07-26T22:33:20.725Z,1564180400.725 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-07-26T22:33:20.866Z,1564180400.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-07-26T22:33:20.867Z,1564180400.867 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-07-26T22:33:21.437Z,1564180401.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-07-26T22:33:21.437Z,1564180401.437 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-07-26T22:33:21.911Z,1564180401.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-07-26T22:33:21.911Z,1564180401.911 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-07-26T22:33:22.455Z,1564180402.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-07-26T22:33:22.455Z,1564180402.455 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-07-26T22:33:22.764Z,1564180402.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-07-26T22:33:22.765Z,1564180402.765 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-07-26T22:33:23.100Z,1564180403.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-07-26T22:33:23.101Z,1564180403.101 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-07-26T22:33:23.500Z,1564180403.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-07-26T22:33:23.501Z,1564180403.501 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-07-26T22:33:23.654Z,1564180403.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-07-26T22:33:23.655Z,1564180403.655 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-07-26T22:33:23.763Z,1564180403.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-07-26T22:33:23.764Z,1564180403.764 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-07-26T22:33:23.848Z,1564180403.848 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-07-26T22:33:23.953Z,1564180403.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-07-26T22:33:23.953Z,1564180403.953 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-07-26T22:33:24.152Z,1564180404.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-07-26T22:33:24.152Z,1564180404.152 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-07-26T22:33:24.384Z,1564180404.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-07-26T22:33:24.386Z,1564180404.386 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-07-26T22:33:24.387Z,1564180404.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-07-26T22:33:25.133Z,1564180405.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-07-26T22:33:25.491Z,1564180405.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-07-26T22:33:25.491Z,1564180405.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-07-26T22:33:25.592Z,1564180405.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-07-26T22:33:25.768Z,1564180405.768 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-07-26T22:33:25.986Z,1564180405.986 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-07-26T22:33:26.075Z,1564180406.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-07-26T22:33:26.182Z,1564180406.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-07-26T22:33:26.281Z,1564180406.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-07-26T22:33:26.444Z,1564180406.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-07-26T22:33:26.555Z,1564180406.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-07-26T22:33:26.659Z,1564180406.659 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-07-26T22:33:26.659Z,1564180406.659 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-07-26T22:33:26.668Z,1564180406.668 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-07-26T22:33:26.769Z,1564180406.769 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-07-26T22:33:26.770Z,1564180406.770 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-07-26T22:33:26.877Z,1564180406.877 [BuoyancyServo] Loaded 2019-07-26T22:33:26.877Z,1564180406.877 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-07-26T22:33:26.892Z,1564180406.892 [ElevatorServo] Loaded 2019-07-26T22:33:26.892Z,1564180406.892 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-07-26T22:33:26.907Z,1564180406.907 [MassServo] Loaded 2019-07-26T22:33:26.907Z,1564180406.907 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-07-26T22:33:26.922Z,1564180406.922 [RudderServo] Loaded 2019-07-26T22:33:26.923Z,1564180406.923 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-07-26T22:33:26.937Z,1564180406.937 [ThrusterServo] Loaded 2019-07-26T22:33:26.937Z,1564180406.937 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-07-26T22:33:26.938Z,1564180406.938 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-07-26T22:33:26.938Z,1564180406.938 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-07-26T22:33:27.076Z,1564180407.076 [SBIT](DEBUG): Construct Startup Built In Test. 2019-07-26T22:33:27.105Z,1564180407.105 [SBIT] Loaded 2019-07-26T22:33:27.105Z,1564180407.105 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-07-26T22:33:27.106Z,1564180407.106 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-07-26T22:33:27.133Z,1564180407.133 [IBIT] Loaded 2019-07-26T22:33:27.134Z,1564180407.134 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-07-26T22:33:27.137Z,1564180407.137 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-07-26T22:33:27.523Z,1564180407.523 [CBIT] Loaded 2019-07-26T22:33:27.524Z,1564180407.524 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-07-26T22:33:27.524Z,1564180407.524 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-07-26T22:33:27.525Z,1564180407.525 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-07-26T22:33:27.715Z,1564180407.715 [Aanderaa_O2] Loaded 2019-07-26T22:33:27.715Z,1564180407.715 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-07-26T22:33:27.725Z,1564180407.725 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-07-26T22:33:27.730Z,1564180407.730 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-07-26T22:33:27.732Z,1564180407.732 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-07-26T22:33:27.737Z,1564180407.737 [CTD_Seabird](INFO): created writer for : depth 2019-07-26T22:33:27.738Z,1564180407.738 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-07-26T22:33:27.743Z,1564180407.743 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-07-26T22:33:27.743Z,1564180407.743 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-07-26T22:33:27.749Z,1564180407.749 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-07-26T22:33:27.749Z,1564180407.749 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-07-26T22:33:27.754Z,1564180407.754 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-07-26T22:33:27.755Z,1564180407.755 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-07-26T22:33:27.760Z,1564180407.760 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-07-26T22:33:27.761Z,1564180407.761 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-07-26T22:33:27.766Z,1564180407.766 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-07-26T22:33:27.792Z,1564180407.792 [CTD_Seabird] Loaded 2019-07-26T22:33:27.792Z,1564180407.792 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-07-26T22:33:27.794Z,1564180407.794 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066B4E0 2019-07-26T22:33:27.794Z,1564180407.794 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5821 2019-07-26T22:33:27.824Z,1564180407.824 [ESPComponent] Loaded 2019-07-26T22:33:27.825Z,1564180407.825 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-07-26T22:33:27.839Z,1564180407.839 [PAR_Licor] Loaded 2019-07-26T22:33:27.839Z,1564180407.839 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-07-26T22:33:27.886Z,1564180407.886 [WetLabsBB2FL] Loaded 2019-07-26T22:33:27.887Z,1564180407.887 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-07-26T22:33:27.888Z,1564180407.888 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069B4E0 2019-07-26T22:33:27.888Z,1564180407.888 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5822 2019-07-26T22:33:27.889Z,1564180407.889 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-07-26T22:33:27.889Z,1564180407.889 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-07-26T22:33:27.932Z,1564180407.932 [DepthRateCalculator] Loaded 2019-07-26T22:33:27.932Z,1564180407.932 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-07-26T22:33:27.938Z,1564180407.938 [PitchRateCalculator] Loaded 2019-07-26T22:33:27.938Z,1564180407.938 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-07-26T22:33:27.954Z,1564180407.954 [SpeedCalculator] Loaded 2019-07-26T22:33:27.954Z,1564180407.954 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-07-26T22:33:27.975Z,1564180407.975 [TempGradientCalculator] Loaded 2019-07-26T22:33:27.975Z,1564180407.975 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-07-26T22:33:27.981Z,1564180407.981 [YawRateCalculator] Loaded 2019-07-26T22:33:27.981Z,1564180407.981 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-07-26T22:33:28.013Z,1564180408.013 [ElevatorOffsetCalculator] Loaded 2019-07-26T22:33:28.014Z,1564180408.014 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-07-26T22:33:28.014Z,1564180408.014 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-07-26T22:33:28.015Z,1564180408.015 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-07-26T22:33:28.048Z,1564180408.048 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-07-26T22:33:28.048Z,1564180408.048 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-07-26T22:33:28.153Z,1564180408.153 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-07-26T22:33:28.153Z,1564180408.153 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-07-26T22:33:28.174Z,1564180408.174 [NavChart] Loaded 2019-07-26T22:33:28.174Z,1564180408.174 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-07-26T22:33:28.178Z,1564180408.178 [UniversalFixResidualReporter] Loaded 2019-07-26T22:33:28.179Z,1564180408.179 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-07-26T22:33:28.179Z,1564180408.179 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-07-26T22:33:28.180Z,1564180408.180 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-07-26T22:33:28.676Z,1564180408.676 [AHRS_M2] Loaded 2019-07-26T22:33:28.677Z,1564180408.677 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-07-26T22:33:28.776Z,1564180408.776 [DataOverHttps] Loaded 2019-07-26T22:33:28.776Z,1564180408.776 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-07-26T22:33:28.777Z,1564180408.777 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409744E0 2019-07-26T22:33:28.778Z,1564180408.778 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5823 2019-07-26T22:33:28.791Z,1564180408.791 [Depth_Keller] Loaded 2019-07-26T22:33:28.791Z,1564180408.791 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-07-26T22:33:28.882Z,1564180408.882 [NAL9602] Loaded 2019-07-26T22:33:28.882Z,1564180408.882 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-07-26T22:33:28.887Z,1564180408.887 [Onboard] Loaded 2019-07-26T22:33:28.888Z,1564180408.888 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-07-26T22:33:28.891Z,1564180408.891 [Radio_Surface] Loaded 2019-07-26T22:33:28.891Z,1564180408.891 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-07-26T22:33:28.892Z,1564180408.892 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0 2019-07-26T22:33:28.892Z,1564180408.892 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5824 2019-07-26T22:33:29.018Z,1564180409.018 [DAT] Loaded 2019-07-26T22:33:29.019Z,1564180409.019 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-07-26T22:33:30.872Z,1564180410.872 [BPC1] Loaded 2019-07-26T22:33:30.872Z,1564180410.872 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-07-26T22:33:30.872Z,1564180410.872 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-07-26T22:33:30.873Z,1564180410.873 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-07-26T22:33:30.946Z,1564180410.946 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-07-26T22:33:30.947Z,1564180410.947 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-07-26T22:33:31.014Z,1564180411.014 [VerticalControl](DEBUG): Construct VerticalControl. 2019-07-26T22:33:31.096Z,1564180411.096 [VerticalControl] Loaded 2019-07-26T22:33:31.097Z,1564180411.097 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-07-26T22:33:31.097Z,1564180411.097 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-07-26T22:33:31.155Z,1564180411.155 [HorizontalControl] Loaded 2019-07-26T22:33:31.155Z,1564180411.155 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-07-26T22:33:31.156Z,1564180411.156 [SpeedControl](DEBUG): Construct SpeedControl. 2019-07-26T22:33:31.157Z,1564180411.157 [SpeedControl] Loaded 2019-07-26T22:33:31.158Z,1564180411.158 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-07-26T22:33:31.158Z,1564180411.158 [LoopControl](DEBUG): Construct LoopControl. 2019-07-26T22:33:31.159Z,1564180411.159 [LoopControl] Loaded 2019-07-26T22:33:31.159Z,1564180411.159 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-07-26T22:33:31.159Z,1564180411.159 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-07-26T22:33:31.160Z,1564180411.160 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-07-26T22:33:31.174Z,1564180411.174 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-07-26T22:33:31.174Z,1564180411.174 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-07-26T22:33:31.472Z,1564180411.472 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-07-26T22:33:31.476Z,1564180411.476 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-07-26T22:33:31.477Z,1564180411.477 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-07-26T22:33:31.484Z,1564180411.484 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-07-26T22:33:31.485Z,1564180411.485 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2019-07-26T22:33:31.485Z,1564180411.485 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5825 2019-07-26T22:33:31.490Z,1564180411.490 [Supervisor](INFO): Main Thread ID is 5737 2019-07-26T22:33:31.490Z,1564180411.490 [Supervisor](DEBUG): Running supervisor. 2019-07-26T22:33:31.491Z,1564180411.491 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5826 2019-07-26T22:33:31.493Z,1564180411.493 [controlThread ThreadHandler](INFO): Handler Thread ID is 5827 2019-07-26T22:33:31.494Z,1564180411.494 [controlThread](DEBUG): Initializing ControlThread 2019-07-26T22:33:31.496Z,1564180411.496 [SBIT](INFO): Initialize SBIT Component. 2019-07-26T22:33:31.496Z,1564180411.496 [SBIT](IMPORTANT): git: 2019-07-26 2019-07-26T22:33:31.496Z,1564180411.496 [SBIT](INFO): git hash: f88ffa24b8ecf5258b33f27687ba0d8562856deb 2019-07-26T22:33:31.497Z,1564180411.497 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-07-26T22:33:31.498Z,1564180411.498 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-07-26T22:33:31.499Z,1564180411.499 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2019-07-26T22:33:31.500Z,1564180411.500 [IBIT](INFO): Initialize IBIT Component. 2019-07-26T22:33:31.500Z,1564180411.500 [CBIT](DEBUG): Initialize CBIT Component. 2019-07-26T22:33:31.502Z,1564180411.502 [logger ThreadHandler](INFO): Handler Thread ID is 5828 2019-07-26T22:33:31.513Z,1564180411.513 [CBIT](DEBUG): Initialized mux pins. 2019-07-26T22:33:31.513Z,1564180411.513 [CBIT](DEBUG): Initializing the watchdog timer. 2019-07-26T22:33:31.522Z,1564180411.522 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5829 2019-07-26T22:33:31.522Z,1564180411.522 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-07-26T22:33:31.526Z,1564180411.526 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-26T22:33:31.527Z,1564180411.527 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5831 2019-07-26T22:33:31.528Z,1564180411.528 [WetLabsBB2FL](INFO): Powering down 2019-07-26T22:33:31.538Z,1564180411.538 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-07-26T22:33:31.538Z,1564180411.538 [CBIT](DEBUG): Initializing heartbeat. 2019-07-26T22:33:31.558Z,1564180411.558 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5832 2019-07-26T22:33:31.559Z,1564180411.559 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-07-26T22:33:31.566Z,1564180411.566 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5833 2019-07-26T22:33:31.586Z,1564180411.586 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5834 2019-07-26T22:33:31.595Z,1564180411.595 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-07-26T22:33:31.595Z,1564180411.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-07-26T22:33:31.595Z,1564180411.595 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-07-26T22:33:31.595Z,1564180411.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-07-26T22:33:31.596Z,1564180411.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-07-26T22:33:31.596Z,1564180411.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-07-26T22:33:31.596Z,1564180411.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-07-26T22:33:31.596Z,1564180411.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-07-26T22:33:31.596Z,1564180411.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-07-26T22:33:31.596Z,1564180411.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-07-26T22:33:31.597Z,1564180411.597 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-07-26T22:33:31.597Z,1564180411.597 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-07-26T22:33:31.597Z,1564180411.597 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-07-26T22:33:31.597Z,1564180411.597 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-07-26T22:33:31.598Z,1564180411.598 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-07-26T22:33:31.598Z,1564180411.598 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-07-26T22:33:31.609Z,1564180411.609 [CBIT](DEBUG): Deactivating GF circuits. 2019-07-26T22:33:31.609Z,1564180411.609 [CBIT](DEBUG): Deactivating emergency mode. 2019-07-26T22:33:31.645Z,1564180411.645 [CBIT](DEBUG): Backplane powered. 2019-07-26T22:33:31.650Z,1564180411.650 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-07-26T22:33:31.650Z,1564180411.650 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-07-26T22:33:31.651Z,1564180411.651 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-07-26T22:33:31.651Z,1564180411.651 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-07-26T22:33:31.652Z,1564180411.652 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-07-26T22:33:31.652Z,1564180411.652 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-07-26T22:33:31.653Z,1564180411.653 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-07-26T22:33:31.654Z,1564180411.654 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-07-26T22:33:31.663Z,1564180411.663 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-07-26T22:33:31.664Z,1564180411.664 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-07-26T22:33:31.673Z,1564180411.673 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-07-26T22:33:31.674Z,1564180411.674 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-07-26T22:33:31.675Z,1564180411.675 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-07-26T22:33:31.707Z,1564180411.707 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-07-26T22:33:31.751Z,1564180411.751 [MissionManager](DEBUG): 2019-07-26T22:33:31.751Z,1564180411.751 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-07-26T22:33:31.807Z,1564180411.807 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-07-26T22:33:31.808Z,1564180411.808 [Default:A.Wait](DEBUG): Construct Wait. 2019-07-26T22:33:31.830Z,1564180411.830 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-07-26T22:33:31.869Z,1564180411.869 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-07-26T22:33:31.872Z,1564180411.872 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-07-26T22:33:31.909Z,1564180411.909 [Default:E.Execute](DEBUG): Construct Execute. 2019-07-26T22:33:31.913Z,1564180411.913 [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 2019-07-26T22:33:31.929Z,1564180411.929 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-07-26T22:33:31.970Z,1564180411.970 [Radio_Surface](INFO): Powering up 2019-07-26T22:33:31.995Z,1564180411.995 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-07-26T22:33:32.260Z,1564180412.260 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-07-26T22:33:32.260Z,1564180412.260 [DAT](INFO): Powering up 2019-07-26T22:33:32.260Z,1564180412.260 [DAT](DEBUG): Initializing DAT. 2019-07-26T22:33:32.306Z,1564180412.306 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-07-26T22:33:32.400Z,1564180412.400 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-07-26T22:33:32.406Z,1564180412.406 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-07-26T22:33:32.407Z,1564180412.407 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-07-26T22:33:32.414Z,1564180412.414 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-07-26T22:33:32.415Z,1564180412.415 [MassServo](DEBUG): Initializing EZServoServo. 2019-07-26T22:33:32.422Z,1564180412.422 [MassServo](DEBUG): Initializing MassServo. 2019-07-26T22:33:32.422Z,1564180412.422 [RudderServo](DEBUG): Initializing EZServoServo. 2019-07-26T22:33:32.430Z,1564180412.430 [RudderServo](DEBUG): Initializing RudderServo. 2019-07-26T22:33:32.430Z,1564180412.430 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-07-26T22:33:32.438Z,1564180412.438 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-07-26T22:33:35.597Z,1564180415.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:36.077Z,1564180416.077 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:36.657Z,1564180416.657 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:37.381Z,1564180417.381 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:38.097Z,1564180418.097 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:38.561Z,1564180418.561 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:39.009Z,1564180419.009 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:39.441Z,1564180419.441 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:39.869Z,1564180419.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:40.309Z,1564180420.309 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:40.833Z,1564180420.833 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:41.269Z,1564180421.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:41.713Z,1564180421.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:42.173Z,1564180422.173 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:42.266Z,1564180422.266 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-07-26T22:33:42.266Z,1564180422.266 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-07-26T22:33:42.266Z,1564180422.266 [Aanderaa_O2](ERROR): Communications Fault 2019-07-26T22:33:42.649Z,1564180422.649 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:42.713Z,1564180422.713 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-07-26T22:33:42.861Z,1564180422.861 [Aanderaa_O2](INFO): Powering down 2019-07-26T22:33:43.233Z,1564180423.233 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:43.681Z,1564180423.681 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:44.121Z,1564180424.121 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:44.213Z,1564180424.213 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-07-26T22:33:44.213Z,1564180424.213 [Aanderaa_O2] No Fault, FailCount= 1 2019-07-26T22:33:44.573Z,1564180424.573 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-07-26T22:33:44.573Z,1564180424.573 [CTD_Seabird] Communications Fault, FailCount= 1 2019-07-26T22:33:44.574Z,1564180424.574 [CTD_Seabird](ERROR): Communications Fault 2019-07-26T22:33:44.605Z,1564180424.605 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:44.670Z,1564180424.670 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-07-26T22:33:44.978Z,1564180424.978 [CTD_Seabird](INFO): Powering down 2019-07-26T22:33:45.045Z,1564180425.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:45.045Z,1564180425.045 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-07-26T22:33:45.046Z,1564180425.046 [AHRS_M2] Communications Fault, FailCount= 1 2019-07-26T22:33:45.046Z,1564180425.046 [AHRS_M2](ERROR): Communications Fault 2019-07-26T22:33:45.126Z,1564180425.126 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-07-26T22:33:45.156Z,1564180425.156 [AHRS_M2](INFO): Powering down 2019-07-26T22:33:46.034Z,1564180426.034 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-07-26T22:33:46.034Z,1564180426.034 [CTD_Seabird] No Fault, FailCount= 1 2019-07-26T22:33:46.035Z,1564180426.035 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-07-26T22:33:46.035Z,1564180426.035 [AHRS_M2] No Fault, FailCount= 1 2019-07-26T22:33:46.346Z,1564180426.346 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-07-26T22:33:46.347Z,1564180426.347 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-26T22:33:46.370Z,1564180426.370 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-07-26T22:33:46.379Z,1564180426.379 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004739 2019-07-26T22:33:47.609Z,1564180427.609 [DAT](INFO): setting local address to 3 2019-07-26T22:33:48.006Z,1564180428.006 [DAT](INFO): set local address to 3 2019-07-26T22:33:49.914Z,1564180429.914 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:50.353Z,1564180430.353 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:50.809Z,1564180430.809 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:51.249Z,1564180431.249 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:51.713Z,1564180431.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:52.145Z,1564180432.145 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:52.200Z,1564180432.200 [SBIT](IMPORTANT): Beginning Startup BIT 2019-07-26T22:33:52.204Z,1564180432.204 [CBIT](IMPORTANT): Beginning ground fault scan 2019-07-26T22:33:52.385Z,1564180432.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:52.945Z,1564180432.945 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:53.397Z,1564180433.397 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:53.842Z,1564180433.842 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:54.457Z,1564180434.457 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:54.992Z,1564180434.992 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-07-26T22:33:54.992Z,1564180434.992 [Aanderaa_O2] Communications Fault, FailCount= 2 2019-07-26T22:33:54.992Z,1564180434.992 [Aanderaa_O2](ERROR): Communications Fault 2019-07-26T22:33:55.317Z,1564180435.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:55.499Z,1564180435.499 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-07-26T22:33:55.737Z,1564180435.737 [Aanderaa_O2](INFO): Powering down 2019-07-26T22:33:56.057Z,1564180436.057 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:56.613Z,1564180436.613 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:57.157Z,1564180437.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:57.394Z,1564180437.394 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-07-26T22:33:57.394Z,1564180437.394 [Aanderaa_O2] No Fault, FailCount= 2 2019-07-26T22:33:57.729Z,1564180437.729 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:58.265Z,1564180438.265 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:58.701Z,1564180438.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:59.129Z,1564180439.129 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:59.135Z,1564180439.135 [NAL9602](INFO): Powering up NAL9602 2019-07-26T22:33:59.397Z,1564180439.397 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-07-26T22:33:59.398Z,1564180439.398 [CTD_Seabird] Communications Fault, FailCount= 2 2019-07-26T22:33:59.398Z,1564180439.398 [CTD_Seabird](ERROR): Communications Fault 2019-07-26T22:33:59.557Z,1564180439.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:33:59.557Z,1564180439.557 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-07-26T22:33:59.558Z,1564180439.558 [AHRS_M2] Communications Fault, FailCount= 2 2019-07-26T22:33:59.558Z,1564180439.558 [AHRS_M2](ERROR): Communications Fault 2019-07-26T22:33:59.666Z,1564180439.666 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-07-26T22:33:59.667Z,1564180439.667 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-07-26T22:33:59.794Z,1564180439.794 [AHRS_M2](INFO): Powering down 2019-07-26T22:33:59.802Z,1564180439.802 [CTD_Seabird](INFO): Powering down 2019-07-26T22:34:00.690Z,1564180440.690 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-07-26T22:34:00.690Z,1564180440.690 [AHRS_M2] No Fault, FailCount= 2 2019-07-26T22:34:00.990Z,1564180440.990 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-07-26T22:34:01.094Z,1564180441.094 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-07-26T22:34:01.094Z,1564180441.094 [CTD_Seabird] No Fault, FailCount= 2 2019-07-26T22:34:01.150Z,1564180441.150 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-07-26T22:34:01.151Z,1564180441.151 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-26T22:34:03.913Z,1564180443.913 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005781 CHAN A1 (24V): -0.022890 CHAN A2 (12V): -0.006009 CHAN A3 (5V): -0.003935 CHAN B0 (3.3V): -0.001925 CHAN B1 (3.15aV): -0.001544 CHAN B2 (3.15bV): -0.001294 CHAN B3 (GND): -0.000694 OPEN: 0.007443 Full Scale Calc: 4.765 mA, -1.589 mA 2019-07-26T22:34:04.537Z,1564180444.537 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:04.969Z,1564180444.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:05.421Z,1564180445.421 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:05.857Z,1564180445.857 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:06.385Z,1564180446.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:06.825Z,1564180446.825 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:07.297Z,1564180447.297 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:07.745Z,1564180447.745 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:07.995Z,1564180447.995 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-07-26T22:34:07.995Z,1564180447.995 [Aanderaa_O2] Communications Fault, FailCount= 3 2019-07-26T22:34:07.995Z,1564180447.995 [Aanderaa_O2](ERROR): Communications Fault 2019-07-26T22:34:08.341Z,1564180448.341 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:08.838Z,1564180448.838 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-07-26T22:34:08.839Z,1564180448.839 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2019-07-26T22:34:08.965Z,1564180448.965 [Aanderaa_O2](INFO): Powering down 2019-07-26T22:34:09.285Z,1564180449.285 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:09.769Z,1564180449.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:10.249Z,1564180450.249 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:10.255Z,1564180450.255 [NAL9602](INFO): NAL9602 initialized 2019-07-26T22:34:10.734Z,1564180450.734 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:11.241Z,1564180451.241 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:11.257Z,1564180451.257 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:34:11.745Z,1564180451.745 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:12.249Z,1564180452.249 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:12.717Z,1564180452.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:13.201Z,1564180453.201 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:13.793Z,1564180453.793 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:14.201Z,1564180454.201 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-07-26T22:34:14.201Z,1564180454.201 [CTD_Seabird] Communications Fault, FailCount= 3 2019-07-26T22:34:14.202Z,1564180454.202 [CTD_Seabird](ERROR): Communications Fault 2019-07-26T22:34:14.337Z,1564180454.337 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:14.337Z,1564180454.337 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-07-26T22:34:14.337Z,1564180454.337 [AHRS_M2] Communications Fault, FailCount= 3 2019-07-26T22:34:14.338Z,1564180454.338 [AHRS_M2](ERROR): Communications Fault 2019-07-26T22:34:14.450Z,1564180454.450 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-07-26T22:34:14.451Z,1564180454.451 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-07-26T22:34:14.451Z,1564180454.451 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-07-26T22:34:14.478Z,1564180454.478 [AHRS_M2](INFO): Powering down 2019-07-26T22:34:14.605Z,1564180454.605 [CTD_Seabird](INFO): Powering down 2019-07-26T22:34:15.358Z,1564180455.358 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-07-26T22:34:15.359Z,1564180455.359 [AHRS_M2] No Fault, FailCount= 3 2019-07-26T22:34:15.670Z,1564180455.670 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-07-26T22:34:19.225Z,1564180459.225 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:19.685Z,1564180459.685 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:20.121Z,1564180460.121 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:20.573Z,1564180460.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:21.041Z,1564180461.041 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:21.461Z,1564180461.461 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:21.893Z,1564180461.893 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:22.341Z,1564180462.341 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:22.781Z,1564180462.781 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:23.313Z,1564180463.313 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:23.737Z,1564180463.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:24.181Z,1564180464.181 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:24.617Z,1564180464.617 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:25.045Z,1564180465.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:25.509Z,1564180465.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:25.953Z,1564180465.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:26.393Z,1564180466.393 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:26.857Z,1564180466.857 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:27.293Z,1564180467.293 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:27.725Z,1564180467.725 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:28.165Z,1564180468.165 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:28.645Z,1564180468.645 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:28.645Z,1564180468.645 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-07-26T22:34:28.646Z,1564180468.646 [AHRS_M2] Communications Fault, FailCount= 4 2019-07-26T22:34:28.646Z,1564180468.646 [AHRS_M2](ERROR): Communications Fault 2019-07-26T22:34:28.742Z,1564180468.742 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-07-26T22:34:28.757Z,1564180468.757 [AHRS_M2](INFO): Powering down 2019-07-26T22:34:29.585Z,1564180469.585 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-07-26T22:34:29.585Z,1564180469.585 [AHRS_M2] No Fault, FailCount= 4 2019-07-26T22:34:29.966Z,1564180469.966 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-07-26T22:34:33.513Z,1564180473.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:33.969Z,1564180473.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:34.405Z,1564180474.405 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:34.861Z,1564180474.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:35.433Z,1564180475.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:36.253Z,1564180476.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:36.749Z,1564180476.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:37.237Z,1564180477.237 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:37.721Z,1564180477.721 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:38.301Z,1564180478.301 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:38.340Z,1564180478.340 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:34:38.957Z,1564180478.957 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:39.401Z,1564180479.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:39.861Z,1564180479.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:40.345Z,1564180480.345 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:40.805Z,1564180480.805 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:41.253Z,1564180481.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:41.677Z,1564180481.677 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:42.157Z,1564180482.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:42.601Z,1564180482.601 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:43.029Z,1564180483.029 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-07-26T22:34:43.029Z,1564180483.029 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-07-26T22:34:43.029Z,1564180483.029 [AHRS_M2] Communications Fault, FailCount= 5 2019-07-26T22:34:43.030Z,1564180483.030 [AHRS_M2](ERROR): Communications Fault 2019-07-26T22:34:43.101Z,1564180483.101 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-07-26T22:34:43.102Z,1564180483.102 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2019-07-26T22:34:43.150Z,1564180483.150 [AHRS_M2](INFO): Powering down 2019-07-26T22:34:43.264Z,1564180483.264 [CommandLine](FAULT): Scheduling is paused 2019-07-26T22:34:43.264Z,1564180483.264 [CBIT](INFO): Critical error at 20190726T223443 2019-07-26T22:34:43.264Z,1564180483.264 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-07-26T22:34:46.372Z,1564180486.372 [SBIT](IMPORTANT): SBIT PASSED 2019-07-26T22:34:46.413Z,1564180486.413 [CommandLine](IMPORTANT): got command configSet list 2019-07-26T22:34:46.413Z,1564180486.413 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-07-26T22:34:46.414Z,1564180486.414 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2019-07-26T22:34:46.414Z,1564180486.414 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpDepth=5 meter; 2019-07-26T22:34:46.414Z,1564180486.414 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-07-26T22:34:46.414Z,1564180486.414 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-07-26T22:34:46.414Z,1564180486.414 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=210 cubic_centimeter; 2019-07-26T22:34:46.414Z,1564180486.414 [CommandLine](IMPORTANT): VerticalControl.kiDepthBuoy=0.3 reciprocal_second; 2019-07-26T22:34:46.415Z,1564180486.415 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 millimeter; 2019-07-26T22:34:46.782Z,1564180486.782 [MissionManager](IMPORTANT): Started mission Startup 2019-07-26T22:34:46.782Z,1564180486.782 [Startup] Running Loop=1 2019-07-26T22:34:46.782Z,1564180486.782 [Startup](DEBUG): Aggregate::initialize Startup 2019-07-26T22:34:46.782Z,1564180486.782 [Startup:A.GoToSurface] Running Loop=1 2019-07-26T22:34:46.783Z,1564180486.783 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-07-26T22:34:46.783Z,1564180486.783 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-07-26T22:34:46.784Z,1564180486.784 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-07-26T22:34:46.784Z,1564180486.784 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-07-26T22:34:46.784Z,1564180486.784 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-07-26T22:34:46.785Z,1564180486.785 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-07-26T22:34:46.786Z,1564180486.786 [Startup:StartupSatComms] Running Loop=1 2019-07-26T22:34:46.786Z,1564180486.786 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-07-26T22:34:46.786Z,1564180486.786 [Startup:StartupSatComms:A] Running Loop=1 2019-07-26T22:34:47.186Z,1564180487.186 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-07-26T22:34:56.921Z,1564180496.921 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:35:03.806Z,1564180503.806 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:35:32.025Z,1564180532.025 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-07-26T22:35:32.025Z,1564180532.025 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-07-26T22:35:32.025Z,1564180532.025 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:35:32.036Z,1564180532.036 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:35:32.458Z,1564180532.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:35:32.458Z,1564180532.458 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-07-26T22:35:46.995Z,1564180546.995 [Startup:StartupSatComms:A](INFO): Timed out from 2019-07-26T22:34:46.8Z 2019-07-26T22:35:46.995Z,1564180546.995 [Startup:StartupSatComms:A] Stopped 2019-07-26T22:35:46.995Z,1564180546.995 [Startup:StartupSatComms:B] Running Loop=1 2019-07-26T22:35:47.390Z,1564180547.390 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-07-26T22:35:54.973Z,1564180554.973 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190726T153853/Courier0021.lzma 2019-07-26T22:35:55.779Z,1564180555.779 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0021.lzma.bak 2019-07-26T22:35:55.779Z,1564180555.779 [DataOverHttps](INFO): SBD MOMSN=11478631 2019-07-26T22:36:05.649Z,1564180565.649 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:36:13.481Z,1564180573.481 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20190726T223320/Courier0000.lzma 2019-07-26T22:36:14.287Z,1564180574.287 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Courier0000.lzma.bak 2019-07-26T22:36:14.287Z,1564180574.287 [DataOverHttps](INFO): SBD MOMSN=11478633 2019-07-26T22:36:32.021Z,1564180592.021 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20190726T153853/Express0022.lzma 2019-07-26T22:36:32.827Z,1564180592.827 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0022.lzma.bak 2019-07-26T22:36:32.827Z,1564180592.827 [DataOverHttps](INFO): SBD MOMSN=11478637 2019-07-26T22:36:38.310Z,1564180598.310 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-07-26T22:36:38.310Z,1564180598.310 [Aanderaa_O2] No Fault, FailCount= 3 2019-07-26T22:36:44.349Z,1564180604.349 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-07-26T22:36:44.349Z,1564180604.349 [CTD_Seabird] No Fault, FailCount= 3 2019-07-26T22:36:44.646Z,1564180604.646 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-07-26T22:36:44.648Z,1564180604.648 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-26T22:36:47.193Z,1564180607.193 [Startup:StartupSatComms:B](INFO): Timed out from 2019-07-26T22:35:46.0Z 2019-07-26T22:36:47.194Z,1564180607.194 [Startup:StartupSatComms:B] Stopped 2019-07-26T22:36:47.194Z,1564180607.194 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-07-26T22:36:47.194Z,1564180607.194 [Startup:StartupSatComms] Stopped 2019-07-26T22:36:47.194Z,1564180607.194 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-07-26T22:36:47.195Z,1564180607.195 [Startup](INFO): Completed Startup 2019-07-26T22:36:47.195Z,1564180607.195 [MissionManager](INFO): Startup is completed. 2019-07-26T22:36:47.195Z,1564180607.195 [MissionManager](INFO): Uninitializing Mission Startup 2019-07-26T22:36:47.195Z,1564180607.195 [Startup] Stopped 2019-07-26T22:36:47.195Z,1564180607.195 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-07-26T22:36:47.195Z,1564180607.195 [Startup:A.GoToSurface] Stopped 2019-07-26T22:36:47.195Z,1564180607.195 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-07-26T22:36:47.586Z,1564180607.586 [MissionManager](IMPORTANT): Started mission Default 2019-07-26T22:36:47.586Z,1564180607.586 [Default] Running Loop=1 2019-07-26T22:36:47.586Z,1564180607.586 [Default](DEBUG): Aggregate::initialize Default 2019-07-26T22:36:47.586Z,1564180607.586 [Default:B.GoToSurface] Running Loop=1 2019-07-26T22:36:47.586Z,1564180607.586 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-07-26T22:36:47.586Z,1564180607.586 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-07-26T22:36:47.587Z,1564180607.587 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-07-26T22:36:47.587Z,1564180607.587 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-07-26T22:36:47.587Z,1564180607.587 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-07-26T22:36:47.588Z,1564180607.588 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-07-26T22:36:47.588Z,1564180607.588 [Default:A.Wait] Running Loop=1 2019-07-26T22:36:47.588Z,1564180607.588 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-07-26T22:36:48.750Z,1564180608.750 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-07-26T22:36:48.750Z,1564180608.750 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-07-26T22:36:48.750Z,1564180608.750 [Aanderaa_O2](ERROR): Communications Fault 2019-07-26T22:36:48.872Z,1564180608.872 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-07-26T22:36:49.274Z,1564180609.274 [Aanderaa_O2](INFO): Powering down 2019-07-26T22:36:50.011Z,1564180610.011 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-07-26T22:36:50.011Z,1564180610.011 [Aanderaa_O2] No Fault, FailCount= 1 2019-07-26T22:36:57.342Z,1564180617.342 [DataOverHttps](INFO): Sending 1066 bytes from file Logs/20190726T223320/Express0001.lzma 2019-07-26T22:36:57.698Z,1564180617.698 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-07-26T22:36:57.698Z,1564180617.698 [CTD_Seabird] Communications Fault, FailCount= 1 2019-07-26T22:36:57.698Z,1564180617.698 [CTD_Seabird](ERROR): Communications Fault 2019-07-26T22:36:58.098Z,1564180618.098 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-07-26T22:36:58.102Z,1564180618.102 [CTD_Seabird](INFO): Powering down 2019-07-26T22:36:58.147Z,1564180618.147 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0001.lzma.bak 2019-07-26T22:36:58.147Z,1564180618.147 [DataOverHttps](INFO): SBD MOMSN=11478641 2019-07-26T22:36:59.322Z,1564180619.322 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-07-26T22:36:59.322Z,1564180619.322 [CTD_Seabird] No Fault, FailCount= 1 2019-07-26T22:36:59.445Z,1564180619.445 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-07-26T22:36:59.447Z,1564180619.447 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-26T22:37:00.466Z,1564180620.466 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-07-26T22:37:00.466Z,1564180620.466 [Aanderaa_O2] Communications Fault, FailCount= 2 2019-07-26T22:37:00.466Z,1564180620.466 [Aanderaa_O2](ERROR): Communications Fault 2019-07-26T22:37:00.530Z,1564180620.530 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-07-26T22:37:00.989Z,1564180620.989 [Aanderaa_O2](INFO): Powering down 2019-07-26T22:37:01.099Z,1564180621.099 [Default:A.Wait](INFO): Done Waiting. 2019-07-26T22:37:01.099Z,1564180621.099 [Default:A.Wait] Stopped 2019-07-26T22:37:01.099Z,1564180621.099 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T22:37:01.311Z,1564180621.311 [Default:CheckIn] Running Loop=1 2019-07-26T22:37:01.311Z,1564180621.311 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T22:37:01.311Z,1564180621.311 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T22:37:01.738Z,1564180621.738 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-07-26T22:37:01.747Z,1564180621.747 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-07-26T22:37:01.747Z,1564180621.747 [Aanderaa_O2] No Fault, FailCount= 2 2019-07-26T22:37:12.182Z,1564180632.182 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-07-26T22:37:12.182Z,1564180632.182 [Aanderaa_O2] Communications Fault, FailCount= 3 2019-07-26T22:37:12.182Z,1564180632.182 [Aanderaa_O2](ERROR): Communications Fault 2019-07-26T22:37:12.284Z,1564180632.284 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:37:12.303Z,1564180632.303 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-07-26T22:37:12.303Z,1564180632.303 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2019-07-26T22:37:12.498Z,1564180632.498 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-07-26T22:37:12.498Z,1564180632.498 [CTD_Seabird] Communications Fault, FailCount= 2 2019-07-26T22:37:12.498Z,1564180632.498 [CTD_Seabird](ERROR): Communications Fault 2019-07-26T22:37:12.706Z,1564180632.706 [Aanderaa_O2](INFO): Powering down 2019-07-26T22:37:12.774Z,1564180632.774 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-07-26T22:37:12.901Z,1564180632.901 [CTD_Seabird](INFO): Powering down 2019-07-26T22:37:13.858Z,1564180633.858 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-07-26T22:37:13.858Z,1564180633.858 [CTD_Seabird] No Fault, FailCount= 2 2019-07-26T22:37:14.245Z,1564180634.245 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-07-26T22:37:14.247Z,1564180634.247 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-26T22:37:23.594Z,1564180643.594 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:37:27.312Z,1564180647.312 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-07-26T22:37:27.312Z,1564180647.312 [CTD_Seabird] Communications Fault, FailCount= 3 2019-07-26T22:37:27.312Z,1564180647.312 [CTD_Seabird](ERROR): Communications Fault 2019-07-26T22:37:27.581Z,1564180647.581 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-07-26T22:37:27.581Z,1564180647.581 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-07-26T22:37:27.714Z,1564180647.714 [CTD_Seabird](INFO): Powering down 2019-07-26T22:37:30.234Z,1564180650.234 [CommandLine](IMPORTANT): got command failComponent 2019-07-26T22:37:30.234Z,1564180650.234 [CommandLine](IMPORTANT): Failed components: 2019-07-26T22:37:30.235Z,1564180650.235 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2019-07-26T22:37:30.235Z,1564180650.235 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault 2019-07-26T22:37:30.235Z,1564180650.235 [CommandLine](IMPORTANT): AHRS_M2: Communications Fault 2019-07-26T22:37:32.058Z,1564180652.058 [CommandLine](IMPORTANT): got command failComponent 2019-07-26T22:37:32.058Z,1564180652.058 [CommandLine](IMPORTANT): Failed components: 2019-07-26T22:37:32.058Z,1564180652.058 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2019-07-26T22:37:32.059Z,1564180652.059 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault 2019-07-26T22:37:32.059Z,1564180652.059 [CommandLine](IMPORTANT): AHRS_M2: Communications Fault 2019-07-26T22:37:32.836Z,1564180652.836 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-07-26T22:37:32.836Z,1564180652.836 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-07-26T22:37:32.836Z,1564180652.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:37:32.855Z,1564180652.855 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:37:33.151Z,1564180653.151 [CommandLine](IMPORTANT): got command failComponent 2019-07-26T22:37:33.151Z,1564180653.151 [CommandLine](IMPORTANT): Failed components: 2019-07-26T22:37:33.151Z,1564180653.151 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2019-07-26T22:37:33.152Z,1564180653.152 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault 2019-07-26T22:37:33.152Z,1564180653.152 [CommandLine](IMPORTANT): DeadReckonUsingMultipleVelocitySources: Software Fault 2019-07-26T22:37:33.152Z,1564180653.152 [CommandLine](IMPORTANT): AHRS_M2: Communications Fault 2019-07-26T22:37:33.294Z,1564180653.294 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:37:33.294Z,1564180653.294 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-07-26T22:37:34.496Z,1564180654.496 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:37:45.004Z,1564180665.004 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:37:55.510Z,1564180675.510 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:38:06.430Z,1564180686.430 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:38:08.367Z,1564180688.367 [BPC1](ERROR): BPC1B failed to parse battery stick message. 2019-07-26T22:38:17.330Z,1564180697.330 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:38:28.244Z,1564180708.244 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:38:39.541Z,1564180719.541 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:38:46.813Z,1564180726.813 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:38:50.451Z,1564180730.451 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:38:57.720Z,1564180737.720 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:01.352Z,1564180741.352 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:08.628Z,1564180748.628 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:12.267Z,1564180752.267 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:14.603Z,1564180754.603 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-07-26T22:39:15.421Z,1564180755.421 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:17.849Z,1564180757.849 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:19.540Z,1564180759.540 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:20.677Z,1564180760.677 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:22.911Z,1564180762.911 [CommandLine](IMPORTANT): got command show variable stopDepth 2019-07-26T22:39:22.966Z,1564180762.966 [CommandLine](IMPORTANT): VerticalControl.stopDepthExcursion (meter) 2019-07-26T22:39:22.980Z,1564180762.980 [CommandLine](IMPORTANT): CBIT.stopDepth (meter) 2019-07-26T22:39:23.176Z,1564180763.176 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:23.505Z,1564180763.505 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:25.529Z,1564180765.529 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:26.463Z,1564180766.463 [CommandLine](IMPORTANT): got command get CBIT.stopDepth 2019-07-26T22:39:26.463Z,1564180766.463 [CommandLine](IMPORTANT): CBIT.stopDepth 40.000000 m 2019-07-26T22:39:27.957Z,1564180767.957 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:30.452Z,1564180770.452 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:30.781Z,1564180770.781 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:34.017Z,1564180774.017 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:34.184Z,1564180774.184 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:34.204Z,1564180774.204 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-07-26T22:39:34.204Z,1564180774.204 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-07-26T22:39:34.204Z,1564180774.204 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:39:34.227Z,1564180774.227 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:39:34.494Z,1564180774.494 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:39:34.494Z,1564180774.494 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-07-26T22:39:36.841Z,1564180776.841 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:40.077Z,1564180780.077 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:41.360Z,1564180781.360 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:42.514Z,1564180782.514 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-07-26T22:39:42.514Z,1564180782.514 [Aanderaa_O2] No Fault, FailCount= 3 2019-07-26T22:39:42.901Z,1564180782.901 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:43.355Z,1564180783.355 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-07-26T22:39:43.355Z,1564180783.355 [AHRS_M2] No Fault, FailCount= 5 2019-07-26T22:39:43.694Z,1564180783.694 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-07-26T22:39:44.994Z,1564180784.994 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:45.729Z,1564180785.729 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:46.234Z,1564180786.234 [Aanderaa_O2](INFO): Powering down 2019-07-26T22:39:48.585Z,1564180788.585 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:51.817Z,1564180791.817 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:52.198Z,1564180792.198 [AHRS_M2](INFO): Failure count cleared after critical for AHRS_M2 2019-07-26T22:39:52.312Z,1564180792.312 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:39:54.629Z,1564180794.629 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:39:57.464Z,1564180797.464 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-07-26T22:39:57.465Z,1564180797.465 [CTD_Seabird] No Fault, FailCount= 3 2019-07-26T22:39:57.729Z,1564180797.729 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-07-26T22:39:57.731Z,1564180797.731 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-26T22:39:57.861Z,1564180797.861 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:00.681Z,1564180800.681 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:03.270Z,1564180803.270 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:40:03.930Z,1564180803.930 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:04.774Z,1564180804.774 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-07-26T22:40:06.757Z,1564180806.757 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:09.985Z,1564180809.985 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:12.805Z,1564180812.805 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:13.515Z,1564180813.515 [CommandLine](IMPORTANT): got command get CBIT.abortDepth 2019-07-26T22:40:13.516Z,1564180813.516 [CommandLine](IMPORTANT): CBIT.abortDepth 50.000000 m 2019-07-26T22:40:14.172Z,1564180814.172 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:40:16.041Z,1564180816.041 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:18.865Z,1564180818.865 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:20.146Z,1564180820.146 [CommandLine](IMPORTANT): got command show variable neutral 2019-07-26T22:40:20.206Z,1564180820.206 [CommandLine](IMPORTANT): Config/Simulator.buoyancyNeutralOffset (cubic_centimeter) 2019-07-26T22:40:20.212Z,1564180820.212 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral (cubic_centimeter) 2019-07-26T22:40:22.097Z,1564180822.097 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:24.933Z,1564180824.933 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:25.526Z,1564180825.526 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:40:25.642Z,1564180825.642 [CommandLine](IMPORTANT): got command get VerticalControl.buoyancyNeutral 2019-07-26T22:40:25.643Z,1564180825.643 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral 210.000000 cc 2019-07-26T22:40:28.153Z,1564180828.153 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:30.985Z,1564180830.985 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:32.359Z,1564180832.359 [CommandLine](IMPORTANT): got command show variable massDef 2019-07-26T22:40:32.403Z,1564180832.403 [CommandLine](IMPORTANT): VerticalControl.massDefault (centimeter) 2019-07-26T22:40:33.821Z,1564180833.821 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:36.459Z,1564180836.459 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:40:36.657Z,1564180836.657 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:38.043Z,1564180838.043 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault 2019-07-26T22:40:38.043Z,1564180838.043 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.000000 cm 2019-07-26T22:40:39.873Z,1564180839.873 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:42.701Z,1564180842.701 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:45.925Z,1564180845.925 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:47.635Z,1564180847.635 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:40:48.777Z,1564180848.777 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:51.997Z,1564180851.997 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:53.671Z,1564180853.671 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:40:54.817Z,1564180854.817 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:58.053Z,1564180858.053 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:40:58.950Z,1564180858.950 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:41:00.877Z,1564180860.877 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:04.113Z,1564180864.113 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:05.346Z,1564180865.346 [CommandLine](IMPORTANT): got command failComponent 2019-07-26T22:41:05.346Z,1564180865.346 [CommandLine](IMPORTANT): Failed components: 2019-07-26T22:41:05.346Z,1564180865.346 [CommandLine](IMPORTANT): No failed Components. 2019-07-26T22:41:06.941Z,1564180866.941 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:07.266Z,1564180867.266 [DataOverHttps](IMPORTANT): SBD MTMSN=20190726T224103 2019-07-26T22:41:10.181Z,1564180870.181 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:10.334Z,1564180870.334 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:41:13.005Z,1564180873.005 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:13.122Z,1564180873.122 [DataOverHttps](INFO): Received command:configSet VerticalControl.massDefault 13 mm persist 2019-07-26T22:41:13.309Z,1564180873.309 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 13.000000 millimeter persist 2019-07-26T22:41:16.253Z,1564180876.253 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:18.709Z,1564180878.709 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:20.697Z,1564180880.697 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:20.851Z,1564180880.851 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:41:22.717Z,1564180882.717 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:25.949Z,1564180885.949 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:28.777Z,1564180888.777 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:31.683Z,1564180891.683 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:41:32.009Z,1564180892.009 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:34.107Z,1564180894.107 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:41:34.841Z,1564180894.841 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:38.069Z,1564180898.069 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:40.893Z,1564180900.893 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:42.652Z,1564180902.652 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:41:43.725Z,1564180903.725 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:46.157Z,1564180906.157 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:47.366Z,1564180907.366 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2019-07-26T22:41:48.980Z,1564180908.980 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:52.210Z,1564180912.210 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:53.497Z,1564180913.497 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:41:55.041Z,1564180915.041 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:41:58.269Z,1564180918.269 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:01.097Z,1564180921.097 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:01.551Z,1564180921.551 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-26T22:37:01.3Z 2019-07-26T22:42:01.551Z,1564180921.551 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T22:42:01.551Z,1564180921.551 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T22:42:01.951Z,1564180921.951 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-07-26T22:42:03.933Z,1564180923.933 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:04.431Z,1564180924.431 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:06.761Z,1564180926.761 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:08.781Z,1564180928.781 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:12.009Z,1564180932.009 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:12.939Z,1564180932.939 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:14.837Z,1564180934.837 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:15.341Z,1564180935.341 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:15.521Z,1564180935.521 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T223320/Courier0004.lzma 2019-07-26T22:42:16.327Z,1564180936.327 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Courier0004.lzma.bak 2019-07-26T22:42:16.327Z,1564180936.327 [DataOverHttps](IMPORTANT): SBD MOMSN=11478679, MTMSN=20190726T224212 2019-07-26T22:42:18.012Z,1564180938.012 [CommandLine](IMPORTANT): got command burn on 2019-07-26T22:42:18.012Z,1564180938.012 [CommandLine](IMPORTANT): Activating dropweight wire 2019-07-26T22:42:18.073Z,1564180938.073 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:20.901Z,1564180940.901 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:23.811Z,1564180943.811 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:24.133Z,1564180944.133 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:24.786Z,1564180944.786 [CommandLine](IMPORTANT): got command burn off 2019-07-26T22:42:24.786Z,1564180944.786 [CommandLine](IMPORTANT): Deactivating dropweight wire 2019-07-26T22:42:26.292Z,1564180946.292 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:26.961Z,1564180946.961 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:28.237Z,1564180948.237 [DataOverHttps](INFO): Received command:configSet DVL_micro.loadAtStartup 1 bool persist 2019-07-26T22:42:28.409Z,1564180948.409 [CommandLine](IMPORTANT): got command configSet DVL_micro.loadAtStartup 1.000000 bool persist 2019-07-26T22:42:28.410Z,1564180948.410 [CommandLine](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect. 2019-07-26T22:42:30.193Z,1564180950.193 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:33.025Z,1564180953.025 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:34.789Z,1564180954.789 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T223320/Courier0007.lzma 2019-07-26T22:42:35.129Z,1564180955.129 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:35.134Z,1564180955.134 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-07-26T22:42:35.135Z,1564180955.135 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:42:35.145Z,1564180955.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:42:35.471Z,1564180955.471 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:42:35.471Z,1564180955.471 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-07-26T22:42:35.619Z,1564180955.619 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Courier0007.lzma.bak 2019-07-26T22:42:35.619Z,1564180955.619 [DataOverHttps](INFO): SBD MOMSN=11478682 2019-07-26T22:42:35.849Z,1564180955.849 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:37.149Z,1564180957.149 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:39.077Z,1564180959.077 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:41.910Z,1564180961.910 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:45.141Z,1564180965.141 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:46.422Z,1564180966.422 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:47.969Z,1564180967.969 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:48.518Z,1564180968.518 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:50.849Z,1564180970.849 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T223320/Courier0009.lzma 2019-07-26T22:42:51.201Z,1564180971.201 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:51.655Z,1564180971.655 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Courier0009.lzma.bak 2019-07-26T22:42:51.655Z,1564180971.655 [DataOverHttps](INFO): SBD MOMSN=11478684 2019-07-26T22:42:54.025Z,1564180974.025 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:56.853Z,1564180976.853 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:57.749Z,1564180977.749 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:42:58.877Z,1564180978.877 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:42:59.376Z,1564180979.376 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:02.109Z,1564180982.109 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:03.854Z,1564180983.854 [DataOverHttps](INFO): Sending 859 bytes from file Logs/20190726T223320/Express0005.lzma 2019-07-26T22:43:04.659Z,1564180984.659 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0005.lzma.bak 2019-07-26T22:43:04.659Z,1564180984.659 [DataOverHttps](INFO): SBD MOMSN=11478686 2019-07-26T22:43:04.937Z,1564180984.937 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:08.165Z,1564180988.165 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:08.726Z,1564180988.726 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:10.270Z,1564180990.270 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:10.997Z,1564180990.997 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:14.230Z,1564180994.230 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:17.057Z,1564180997.057 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:19.292Z,1564180999.292 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20190726T223320/Express0006.lzma 2019-07-26T22:43:19.885Z,1564180999.885 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:19.980Z,1564180999.980 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:20.099Z,1564181000.099 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0006.lzma.bak 2019-07-26T22:43:20.099Z,1564181000.099 [DataOverHttps](INFO): SBD MOMSN=11478737 2019-07-26T22:43:21.177Z,1564181001.177 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:22.309Z,1564181002.309 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:25.137Z,1564181005.137 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:28.366Z,1564181008.366 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:30.871Z,1564181010.871 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:31.198Z,1564181011.198 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:32.571Z,1564181012.571 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:34.433Z,1564181014.433 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:37.253Z,1564181017.253 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:37.497Z,1564181017.497 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T223320/Express0008.lzma 2019-07-26T22:43:38.303Z,1564181018.303 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0008.lzma.bak 2019-07-26T22:43:38.303Z,1564181018.303 [DataOverHttps](INFO): SBD MOMSN=11478739 2019-07-26T22:43:40.086Z,1564181020.086 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:41.853Z,1564181021.853 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:42.913Z,1564181022.913 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:44.933Z,1564181024.933 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:48.165Z,1564181028.165 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:50.997Z,1564181030.997 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:53.090Z,1564181033.090 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:43:54.225Z,1564181034.225 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:57.049Z,1564181037.049 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:43:58.349Z,1564181038.349 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20190726T223320/Express0010.lzma 2019-07-26T22:43:59.155Z,1564181039.155 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0010.lzma.bak 2019-07-26T22:43:59.155Z,1564181039.155 [DataOverHttps](INFO): SBD MOMSN=11478741 2019-07-26T22:43:59.895Z,1564181039.895 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T22:43:59.895Z,1564181039.895 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T22:43:59.895Z,1564181039.895 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T22:44:00.289Z,1564181040.289 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:44:03.109Z,1564181043.109 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:44:04.056Z,1564181044.056 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:44:05.941Z,1564181045.941 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:44:07.961Z,1564181047.961 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:44:10.385Z,1564181050.385 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:44:13.202Z,1564181053.202 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-07-26T22:44:13.203Z,1564181053.203 [NAL9602] Data Fault, FailCount= 1 2019-07-26T22:44:13.203Z,1564181053.203 [NAL9602](ERROR): Data Fault 2019-07-26T22:44:13.234Z,1564181053.234 [CBIT](ERROR): Data Fault in component: NAL9602 2019-07-26T22:44:13.610Z,1564181053.610 [NAL9602](INFO): Powering down 2019-07-26T22:44:14.455Z,1564181054.455 [CBIT](INFO): Clearing failed state for component NAL9602 2019-07-26T22:44:14.455Z,1564181054.455 [NAL9602] No Fault, FailCount= 1 2019-07-26T22:44:15.312Z,1564181055.312 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:44:26.229Z,1564181066.229 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:44:28.219Z,1564181068.219 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-07-26T22:44:36.791Z,1564181076.791 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-07-26T22:44:43.906Z,1564181083.906 [NAL9602](INFO): Powering up NAL9602 2019-07-26T22:44:54.815Z,1564181094.815 [NAL9602](INFO): NAL9602 initialized 2019-07-26T22:44:55.637Z,1564181095.637 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:44:58.057Z,1564181098.057 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:01.293Z,1564181101.293 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:04.113Z,1564181104.113 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:07.349Z,1564181107.349 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:10.173Z,1564181110.173 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:13.001Z,1564181113.001 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:15.029Z,1564181115.029 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:18.257Z,1564181118.257 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:21.085Z,1564181121.085 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:24.317Z,1564181124.317 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:27.145Z,1564181127.145 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:30.385Z,1564181130.385 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:33.201Z,1564181133.201 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:36.058Z,1564181136.058 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:36.089Z,1564181136.089 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-07-26T22:45:36.089Z,1564181136.089 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:45:36.099Z,1564181136.099 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:45:36.493Z,1564181136.493 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:45:36.493Z,1564181136.493 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-07-26T22:45:38.061Z,1564181138.061 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:41.285Z,1564181141.285 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:44.113Z,1564181144.113 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:47.345Z,1564181147.345 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:50.173Z,1564181150.173 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:53.405Z,1564181153.405 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:56.233Z,1564181156.233 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:45:59.061Z,1564181159.061 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:01.485Z,1564181161.485 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:04.313Z,1564181164.313 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:07.545Z,1564181167.545 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:10.373Z,1564181170.373 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:13.201Z,1564181173.201 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:16.433Z,1564181176.433 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:19.261Z,1564181179.261 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:22.501Z,1564181182.501 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:25.321Z,1564181185.321 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:28.557Z,1564181188.557 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:31.385Z,1564181191.385 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:34.613Z,1564181194.613 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:37.445Z,1564181197.445 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:40.673Z,1564181200.673 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:43.501Z,1564181203.501 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:46.732Z,1564181206.732 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:49.557Z,1564181209.557 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:52.389Z,1564181212.389 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:55.213Z,1564181215.213 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:57.237Z,1564181217.237 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:46:59.257Z,1564181219.257 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:02.485Z,1564181222.485 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:05.313Z,1564181225.313 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:08.549Z,1564181228.549 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:11.373Z,1564181231.373 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:14.609Z,1564181234.609 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:17.437Z,1564181237.437 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:20.269Z,1564181240.269 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:22.693Z,1564181242.693 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:25.517Z,1564181245.517 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:28.749Z,1564181248.749 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:31.573Z,1564181251.573 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:34.817Z,1564181254.817 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:37.637Z,1564181257.637 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:40.465Z,1564181260.465 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:43.293Z,1564181263.293 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:45.313Z,1564181265.313 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:47.330Z,1564181267.330 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:50.565Z,1564181270.565 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:53.394Z,1564181273.394 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:56.625Z,1564181276.625 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:47:59.449Z,1564181279.449 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:02.701Z,1564181282.701 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:05.509Z,1564181285.509 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:08.337Z,1564181288.337 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:10.765Z,1564181290.765 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:13.593Z,1564181293.593 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:16.825Z,1564181296.825 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:19.653Z,1564181299.653 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:22.889Z,1564181302.889 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:25.774Z,1564181305.774 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:28.545Z,1564181308.545 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:31.374Z,1564181311.374 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:33.386Z,1564181313.386 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:35.418Z,1564181315.418 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:37.034Z,1564181317.034 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-07-26T22:48:37.035Z,1564181317.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:48:37.044Z,1564181317.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:48:37.443Z,1564181317.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:48:37.444Z,1564181317.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-07-26T22:48:38.645Z,1564181318.645 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:41.473Z,1564181321.473 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:44.701Z,1564181324.701 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:47.533Z,1564181327.533 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:50.761Z,1564181330.761 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:53.589Z,1564181333.589 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:56.417Z,1564181336.417 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:48:58.845Z,1564181338.845 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:00.468Z,1564181340.468 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T22:49:00.468Z,1564181340.468 [Default:CheckIn:C.Wait] Stopped 2019-07-26T22:49:00.468Z,1564181340.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T22:49:00.468Z,1564181340.468 [Default:CheckIn:D] Running Loop=1 2019-07-26T22:49:00.942Z,1564181340.942 [Default:CheckIn:D] Stopped 2019-07-26T22:49:00.942Z,1564181340.942 [Default:CheckIn:E] Running Loop=1 2019-07-26T22:49:01.283Z,1564181341.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.222594 min 2019-07-26T22:49:01.283Z,1564181341.283 [Default:CheckIn:E] Stopped 2019-07-26T22:49:01.283Z,1564181341.283 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T22:49:01.283Z,1564181341.283 [Default:CheckIn] Stopped 2019-07-26T22:49:01.283Z,1564181341.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T22:49:01.283Z,1564181341.283 [Default:CheckIn](INFO): Running loop #2 2019-07-26T22:49:01.284Z,1564181341.284 [Default:CheckIn] Running Loop=2 2019-07-26T22:49:01.284Z,1564181341.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T22:49:01.284Z,1564181341.284 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T22:49:01.665Z,1564181341.665 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:04.901Z,1564181344.901 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:07.725Z,1564181347.725 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:10.961Z,1564181350.961 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:13.789Z,1564181353.789 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:16.617Z,1564181356.617 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:19.445Z,1564181359.445 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:21.469Z,1564181361.469 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:23.485Z,1564181363.485 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:26.717Z,1564181366.717 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:29.569Z,1564181369.569 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:32.789Z,1564181372.789 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:35.609Z,1564181375.609 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:38.837Z,1564181378.837 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:41.661Z,1564181381.661 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:44.497Z,1564181384.497 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:46.913Z,1564181386.913 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:49.748Z,1564181389.748 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:52.977Z,1564181392.977 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:55.805Z,1564181395.805 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:49:59.033Z,1564181399.033 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:01.865Z,1564181401.865 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:04.689Z,1564181404.689 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:07.521Z,1564181407.521 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:09.545Z,1564181409.545 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:11.565Z,1564181411.565 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:14.789Z,1564181414.789 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:17.621Z,1564181417.621 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:20.849Z,1564181420.849 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:23.681Z,1564181423.681 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:26.913Z,1564181426.913 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:29.757Z,1564181429.757 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:32.569Z,1564181432.569 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:34.989Z,1564181434.989 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:37.821Z,1564181437.821 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:41.053Z,1564181441.053 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:43.881Z,1564181443.881 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:47.109Z,1564181447.109 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:49.941Z,1564181449.941 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:52.773Z,1564181452.773 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:55.605Z,1564181455.605 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:57.617Z,1564181457.617 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:50:59.641Z,1564181459.641 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:02.869Z,1564181462.869 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:05.697Z,1564181465.697 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:08.929Z,1564181468.929 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:11.757Z,1564181471.757 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:14.989Z,1564181474.989 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:17.813Z,1564181477.813 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:20.645Z,1564181480.645 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:23.077Z,1564181483.077 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:25.893Z,1564181485.893 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:29.149Z,1564181489.149 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:31.957Z,1564181491.957 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:35.185Z,1564181495.185 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:38.017Z,1564181498.017 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:38.042Z,1564181498.042 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2019-07-26T22:51:38.042Z,1564181498.042 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:51:38.052Z,1564181498.052 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:51:38.458Z,1564181498.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:51:38.459Z,1564181498.459 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2019-07-26T22:51:40.845Z,1564181500.845 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:43.676Z,1564181503.676 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:45.693Z,1564181505.693 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:47.713Z,1564181507.713 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:50.941Z,1564181510.941 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:53.773Z,1564181513.773 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:57.009Z,1564181517.009 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:51:59.833Z,1564181519.833 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:03.065Z,1564181523.065 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:05.889Z,1564181525.889 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:09.125Z,1564181529.125 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:11.949Z,1564181531.949 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:14.781Z,1564181534.781 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:17.205Z,1564181537.205 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:20.033Z,1564181540.033 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:20.880Z,1564181540.880 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-26T22:52:20.882Z,1564181540.882 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-26T22:52:20.882Z,1564181540.882 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-07-26T22:52:20.886Z,1564181540.886 [BPC1](INFO): Received data from all battery sticks. 2019-07-26T22:52:23.265Z,1564181543.265 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:26.094Z,1564181546.094 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:28.925Z,1564181548.925 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:31.749Z,1564181551.749 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:34.977Z,1564181554.977 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:37.813Z,1564181557.813 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:38.108Z,1564181558.108 [CommandLine](IMPORTANT): got command failComponent 2019-07-26T22:52:38.108Z,1564181558.108 [CommandLine](IMPORTANT): Failed components: 2019-07-26T22:52:38.108Z,1564181558.108 [CommandLine](IMPORTANT): No failed Components. 2019-07-26T22:52:41.041Z,1564181561.041 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:43.871Z,1564181563.871 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:47.101Z,1564181567.101 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:49.937Z,1564181569.937 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:53.165Z,1564181573.165 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:55.993Z,1564181575.993 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:52:59.225Z,1564181579.225 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:02.053Z,1564181582.053 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:05.281Z,1564181585.281 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:08.113Z,1564181588.113 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:10.941Z,1564181590.941 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:14.177Z,1564181594.177 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:17.001Z,1564181597.001 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:19.829Z,1564181599.829 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:23.061Z,1564181603.061 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:25.897Z,1564181605.897 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:29.121Z,1564181609.121 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:31.949Z,1564181611.949 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:35.177Z,1564181615.177 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:38.017Z,1564181618.017 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:41.253Z,1564181621.253 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:44.069Z,1564181624.069 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:47.301Z,1564181627.301 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:50.129Z,1564181630.129 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:53.365Z,1564181633.365 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:56.189Z,1564181636.189 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:53:59.421Z,1564181639.421 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:01.458Z,1564181641.458 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-26T22:49:01.3Z 2019-07-26T22:54:01.458Z,1564181641.458 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T22:54:01.458Z,1564181641.458 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T22:54:02.249Z,1564181642.249 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:05.097Z,1564181645.097 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:06.469Z,1564181646.469 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190726T223320/Courier0013.lzma 2019-07-26T22:54:07.275Z,1564181647.275 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Courier0013.lzma.bak 2019-07-26T22:54:07.275Z,1564181647.275 [DataOverHttps](INFO): SBD MOMSN=11478751 2019-07-26T22:54:07.901Z,1564181647.901 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:09.922Z,1564181649.922 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:11.942Z,1564181651.942 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:15.173Z,1564181655.173 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:17.789Z,1564181657.789 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20190726T223320/Express0014.lzma 2019-07-26T22:54:18.009Z,1564181658.009 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:18.595Z,1564181658.595 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0014.lzma.bak 2019-07-26T22:54:18.595Z,1564181658.595 [DataOverHttps](INFO): SBD MOMSN=11478753 2019-07-26T22:54:19.247Z,1564181659.247 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T22:54:19.247Z,1564181659.247 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T22:54:19.247Z,1564181659.247 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T22:54:21.233Z,1564181661.233 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:24.073Z,1564181664.073 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:27.297Z,1564181667.297 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:30.125Z,1564181670.125 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:32.953Z,1564181672.953 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:35.377Z,1564181675.377 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:38.205Z,1564181678.205 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:39.043Z,1564181679.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2019-07-26T22:54:39.043Z,1564181679.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:54:39.053Z,1564181679.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:54:39.436Z,1564181679.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:54:39.436Z,1564181679.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2019-07-26T22:54:41.439Z,1564181681.439 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:44.265Z,1564181684.265 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:47.497Z,1564181687.497 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:50.337Z,1564181690.337 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:53.149Z,1564181693.149 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:54:55.970Z,1564181695.970 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-07-26T22:54:55.971Z,1564181695.971 [NAL9602] Data Fault, FailCount= 2 2019-07-26T22:54:55.971Z,1564181695.971 [NAL9602](ERROR): Data Fault 2019-07-26T22:54:56.001Z,1564181696.001 [CBIT](ERROR): Data Fault in component: NAL9602 2019-07-26T22:54:56.376Z,1564181696.376 [NAL9602](INFO): Powering down 2019-07-26T22:54:57.294Z,1564181697.294 [CBIT](INFO): Clearing failed state for component NAL9602 2019-07-26T22:54:57.294Z,1564181697.294 [NAL9602] No Fault, FailCount= 2 2019-07-26T22:55:26.675Z,1564181726.675 [NAL9602](INFO): Powering up NAL9602 2019-07-26T22:55:37.583Z,1564181737.583 [NAL9602](INFO): NAL9602 initialized 2019-07-26T22:55:38.401Z,1564181738.401 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:55:40.825Z,1564181740.825 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:55:44.057Z,1564181744.057 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:55:46.887Z,1564181746.887 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:55:50.125Z,1564181750.125 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:55:52.941Z,1564181752.941 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:55:55.773Z,1564181755.773 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:55:57.793Z,1564181757.793 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:01.025Z,1564181761.025 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:03.861Z,1564181763.861 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:07.089Z,1564181767.089 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:09.913Z,1564181769.913 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:13.149Z,1564181773.149 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:15.973Z,1564181775.973 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:18.801Z,1564181778.801 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:20.837Z,1564181780.837 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:22.841Z,1564181782.841 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:26.073Z,1564181786.073 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:28.901Z,1564181788.901 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:32.133Z,1564181792.133 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:34.961Z,1564181794.961 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:38.200Z,1564181798.200 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:41.025Z,1564181801.025 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:43.849Z,1564181803.849 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:45.865Z,1564181805.865 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:49.101Z,1564181809.101 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:51.533Z,1564181811.533 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:54.349Z,1564181814.349 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:57.181Z,1564181817.181 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:56:59.605Z,1564181819.605 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:02.433Z,1564181822.433 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:05.665Z,1564181825.665 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:08.496Z,1564181828.496 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:11.321Z,1564181831.321 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:14.553Z,1564181834.553 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:17.381Z,1564181837.381 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:20.213Z,1564181840.213 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:23.437Z,1564181843.437 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:26.269Z,1564181846.269 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:29.501Z,1564181849.501 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:32.325Z,1564181852.325 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:35.561Z,1564181855.561 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:38.389Z,1564181858.389 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:40.015Z,1564181860.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2019-07-26T22:57:40.015Z,1564181860.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T22:57:40.025Z,1564181860.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T22:57:40.427Z,1564181860.427 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T22:57:40.428Z,1564181860.428 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2019-07-26T22:57:41.624Z,1564181861.624 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:44.449Z,1564181864.449 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:47.681Z,1564181867.681 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:50.509Z,1564181870.509 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:53.737Z,1564181873.737 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:56.569Z,1564181876.569 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:57:59.801Z,1564181879.801 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:02.629Z,1564181882.629 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:05.457Z,1564181885.457 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:08.293Z,1564181888.293 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:11.521Z,1564181891.521 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:14.345Z,1564181894.345 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:17.601Z,1564181897.601 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:20.409Z,1564181900.409 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:23.637Z,1564181903.637 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:26.465Z,1564181906.465 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:29.697Z,1564181909.697 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:32.525Z,1564181912.525 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:35.757Z,1564181915.757 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:38.593Z,1564181918.593 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:41.817Z,1564181921.817 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:44.645Z,1564181924.645 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:47.877Z,1564181927.877 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:50.705Z,1564181930.705 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:53.529Z,1564181933.529 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:56.361Z,1564181936.361 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:58:59.593Z,1564181939.593 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:02.424Z,1564181942.424 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:05.665Z,1564181945.665 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:08.481Z,1564181948.481 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:11.709Z,1564181951.709 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:14.546Z,1564181954.546 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:17.781Z,1564181957.781 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:19.814Z,1564181959.814 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T22:59:19.814Z,1564181959.814 [Default:CheckIn:C.Wait] Stopped 2019-07-26T22:59:19.814Z,1564181959.814 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T22:59:19.814Z,1564181959.814 [Default:CheckIn:D] Running Loop=1 2019-07-26T22:59:20.229Z,1564181960.229 [Default:CheckIn:D] Stopped 2019-07-26T22:59:20.229Z,1564181960.229 [Default:CheckIn:E] Running Loop=1 2019-07-26T22:59:20.601Z,1564181960.601 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.544057 min 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn:E] Stopped 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn] Stopped 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn](INFO): Running loop #3 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn] Running Loop=3 2019-07-26T22:59:20.647Z,1564181960.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T22:59:20.648Z,1564181960.648 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T22:59:23.829Z,1564181963.829 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:26.661Z,1564181966.661 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:29.893Z,1564181969.893 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:32.725Z,1564181972.725 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:35.957Z,1564181975.957 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:38.781Z,1564181978.781 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:41.609Z,1564181981.609 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:44.437Z,1564181984.437 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:47.669Z,1564181987.669 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:50.497Z,1564181990.497 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:53.729Z,1564181993.729 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:56.557Z,1564181996.557 [NAL9602](DEBUG): Fix Requested 2019-07-26T22:59:59.785Z,1564181999.785 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:02.621Z,1564182002.621 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:05.853Z,1564182005.853 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:08.677Z,1564182008.677 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:11.909Z,1564182011.909 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:14.753Z,1564182014.753 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:17.969Z,1564182017.969 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:20.797Z,1564182020.797 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:23.629Z,1564182023.629 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:26.457Z,1564182026.457 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:28.473Z,1564182028.473 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:31.705Z,1564182031.705 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:34.533Z,1564182034.533 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:37.765Z,1564182037.765 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:40.593Z,1564182040.593 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:41.034Z,1564182041.034 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2019-07-26T23:00:41.034Z,1564182041.034 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T23:00:41.044Z,1564182041.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T23:00:41.450Z,1564182041.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T23:00:41.450Z,1564182041.450 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2019-07-26T23:00:43.827Z,1564182043.827 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:46.657Z,1564182046.657 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:49.485Z,1564182049.485 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:51.501Z,1564182051.501 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:53.921Z,1564182053.921 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:56.757Z,1564182056.757 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:00:59.989Z,1564182059.989 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:02.817Z,1564182062.817 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:06.045Z,1564182066.045 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:08.873Z,1564182068.873 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:11.697Z,1564182071.697 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:14.533Z,1564182074.533 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:16.553Z,1564182076.553 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:19.781Z,1564182079.781 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:22.613Z,1564182082.613 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:25.837Z,1564182085.837 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:28.669Z,1564182088.669 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:31.901Z,1564182091.901 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:34.729Z,1564182094.729 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:37.557Z,1564182097.557 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:39.577Z,1564182099.577 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:42.001Z,1564182102.001 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:44.829Z,1564182104.829 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:48.061Z,1564182108.061 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:50.889Z,1564182110.889 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:53.717Z,1564182113.717 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:01:56.945Z,1564182116.945 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:00.181Z,1564182120.181 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:03.009Z,1564182123.009 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:06.245Z,1564182126.245 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:09.069Z,1564182129.069 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:11.917Z,1564182131.917 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:14.725Z,1564182134.725 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:17.961Z,1564182137.961 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:20.785Z,1564182140.785 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:24.017Z,1564182144.017 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:26.845Z,1564182146.845 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:28.650Z,1564182148.650 [DataOverHttps](IMPORTANT): SBD MTMSN=20190726T230223 2019-07-26T23:02:30.077Z,1564182150.077 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:32.905Z,1564182152.905 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:34.725Z,1564182154.725 [DataOverHttps](INFO): Received command:Ibit 2019-07-26T23:02:34.770Z,1564182154.770 [CommandLine](IMPORTANT): got command ibit 2019-07-26T23:02:34.960Z,1564182154.960 [IBIT](IMPORTANT): Beginning Initiated BIT 2019-07-26T23:02:34.960Z,1564182154.960 [IBIT](IMPORTANT): Beginning control surface checks. 2019-07-26T23:02:34.964Z,1564182154.964 [CBIT](IMPORTANT): Beginning ground fault scan 2019-07-26T23:02:36.137Z,1564182156.137 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:38.969Z,1564182158.969 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:42.197Z,1564182162.197 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:45.033Z,1564182165.033 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:45.883Z,1564182165.883 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.007049 CHAN A1 (24V): 0.001468 CHAN A2 (12V): 0.003191 CHAN A3 (5V): -0.003152 CHAN B0 (3.3V): -0.003412 CHAN B1 (3.15aV): -0.003312 CHAN B2 (3.15bV): -0.002562 CHAN B3 (GND): -0.005495 OPEN: 0.007368 Full Scale Calc: 4.765 mA, -1.589 mA 2019-07-26T23:02:47.857Z,1564182167.857 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:51.089Z,1564182171.089 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:53.913Z,1564182173.913 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:57.149Z,1564182177.149 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:02:59.973Z,1564182179.973 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:03.201Z,1564182183.201 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:06.033Z,1564182186.033 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:08.881Z,1564182188.881 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:12.093Z,1564182192.093 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:14.921Z,1564182194.921 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:18.153Z,1564182198.153 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:20.981Z,1564182200.981 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:24.213Z,1564182204.213 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:27.041Z,1564182207.041 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:30.273Z,1564182210.273 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:33.097Z,1564182213.097 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:36.336Z,1564182216.336 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:39.165Z,1564182219.165 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:41.985Z,1564182221.985 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:42.005Z,1564182222.005 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2019-07-26T23:03:42.005Z,1564182222.005 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-07-26T23:03:42.014Z,1564182222.014 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-07-26T23:03:42.434Z,1564182222.434 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-07-26T23:03:42.434Z,1564182222.434 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2019-07-26T23:03:45.221Z,1564182225.221 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:48.049Z,1564182228.049 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:51.281Z,1564182231.281 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:54.109Z,1564182234.109 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:03:57.341Z,1564182237.341 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:00.169Z,1564182240.169 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:03.001Z,1564182243.001 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:06.233Z,1564182246.233 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:09.073Z,1564182249.073 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:12.289Z,1564182252.289 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:15.117Z,1564182255.117 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:18.349Z,1564182258.349 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:21.177Z,1564182261.177 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:24.409Z,1564182264.409 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:26.047Z,1564182266.047 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2019-07-26T23:04:26.456Z,1564182266.456 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 0.000000 2019-07-26T23:04:26.456Z,1564182266.456 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2019-07-26T23:04:26.457Z,1564182266.457 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2019-07-26T23:04:26.856Z,1564182266.856 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2019-07-26T23:04:26.856Z,1564182266.856 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2019-07-26T23:04:26.856Z,1564182266.856 [IBIT](IMPORTANT): Pressure:9.696732 PSI 2019-07-26T23:04:26.857Z,1564182266.857 [IBIT](IMPORTANT): Humidity:20.791113 % 2019-07-26T23:04:27.244Z,1564182267.244 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:27.279Z,1564182267.279 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2019-07-26T23:04:27.280Z,1564182267.280 [IBIT](IMPORTANT): buoyancyNeutral: 210.000000 cc 2019-07-26T23:04:27.280Z,1564182267.280 [IBIT](IMPORTANT): massDefault: 1.300000 cm 2019-07-26T23:04:27.280Z,1564182267.280 [IBIT](IMPORTANT): stopDepth: 40.000000 m 2019-07-26T23:04:27.281Z,1564182267.281 [IBIT](IMPORTANT): abortDepth: 50.000000 m 2019-07-26T23:04:27.281Z,1564182267.281 [IBIT](IMPORTANT): IBIT FAILED 2019-07-26T23:04:27.683Z,1564182267.683 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-26T22:59:20.6Z 2019-07-26T23:04:27.683Z,1564182267.683 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T23:04:27.683Z,1564182267.683 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T23:04:30.069Z,1564182270.069 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:33.297Z,1564182273.297 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:34.361Z,1564182274.361 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T223320/Courier0016.lzma 2019-07-26T23:04:35.167Z,1564182275.167 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Courier0016.lzma.bak 2019-07-26T23:04:35.167Z,1564182275.167 [DataOverHttps](INFO): SBD MOMSN=11478762 2019-07-26T23:04:36.133Z,1564182276.133 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:39.369Z,1564182279.369 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:42.181Z,1564182282.181 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:45.413Z,1564182285.413 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:45.721Z,1564182285.721 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T223320/Courier0019.lzma 2019-07-26T23:04:46.527Z,1564182286.527 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Courier0019.lzma.bak 2019-07-26T23:04:46.527Z,1564182286.527 [DataOverHttps](INFO): SBD MOMSN=11478764 2019-07-26T23:04:48.245Z,1564182288.245 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:51.473Z,1564182291.473 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:54.305Z,1564182294.305 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:57.129Z,1564182297.129 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:04:59.246Z,1564182299.246 [DataOverHttps](INFO): Sending 702 bytes from file Logs/20190726T223320/Express0017.lzma 2019-07-26T23:05:00.051Z,1564182300.051 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0017.lzma.bak 2019-07-26T23:05:00.051Z,1564182300.051 [DataOverHttps](INFO): SBD MOMSN=11478766 2019-07-26T23:05:00.369Z,1564182300.369 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:03.193Z,1564182303.193 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:06.429Z,1564182306.429 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:09.257Z,1564182309.257 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:12.481Z,1564182312.481 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:14.633Z,1564182314.633 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190726T223320/Express0020.lzma 2019-07-26T23:05:15.313Z,1564182315.313 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:15.439Z,1564182315.439 [DataOverHttps](INFO): Moved sent file to Logs/20190726T223320/Express0020.lzma.bak 2019-07-26T23:05:15.439Z,1564182315.439 [DataOverHttps](INFO): SBD MOMSN=11478790 2019-07-26T23:05:16.143Z,1564182316.143 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T23:05:16.143Z,1564182316.143 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T23:05:16.143Z,1564182316.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T23:05:18.137Z,1564182318.137 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:21.373Z,1564182321.373 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:24.201Z,1564182324.201 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:27.433Z,1564182327.433 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:05:27.829Z,1564182327.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230522.00,A,3648.15122,N,12147.22495,W,1.030,4.98,260719,,,A*71 2019-07-26T23:05:27.832Z,1564182327.832 [NAL9602](INFO): GPS fix at 20190726T230522: (36.802520, -121.787082) 2019-07-26T23:06:00.148Z,1564182360.148 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-26T23:06:52.683Z,1564182412.683 [DataOverHttps](IMPORTANT): SBD MTMSN=20190726T230646 2019-07-26T23:06:57.721Z,1564182417.721 [DataOverHttps](INFO): Received command:Ibit 2019-07-26T23:06:57.762Z,1564182417.762 [CommandLine](IMPORTANT): got command ibit 2019-07-26T23:06:57.966Z,1564182417.966 [IBIT](IMPORTANT): Beginning Initiated BIT 2019-07-26T23:06:57.966Z,1564182417.966 [IBIT](IMPORTANT): Beginning control surface checks. 2019-07-26T23:06:57.972Z,1564182417.972 [CBIT](IMPORTANT): Beginning ground fault scan 2019-07-26T23:06:59.149Z,1564182419.149 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:06:59.542Z,1564182419.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230653.00,A,3648.14953,N,12147.22274,W,0.058,4.98,260719,,,A*7D 2019-07-26T23:06:59.544Z,1564182419.544 [NAL9602](INFO): GPS fix at 20190726T230653: (36.802492, -121.787046) 2019-07-26T23:07:08.863Z,1564182428.863 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.006164 CHAN A1 (24V): 0.000496 CHAN A2 (12V): 0.001952 CHAN A3 (5V): -0.003584 CHAN B0 (3.3V): -0.003500 CHAN B1 (3.15aV): -0.003142 CHAN B2 (3.15bV): -0.002673 CHAN B3 (GND): -0.005633 OPEN: 0.007593 Full Scale Calc: 4.765 mA, -1.589 mA 2019-07-26T23:07:20.146Z,1564182440.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=30215, MT Status=0, MTMSN=0 2019-07-26T23:07:20.146Z,1564182440.146 [NAL9602](INFO): No messages in MT queue 2019-07-26T23:07:20.965Z,1564182440.965 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:21.358Z,1564182441.358 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230715.00,A,3648.14884,N,12147.22232,W,0.078,4.98,260719,,,D*70 2019-07-26T23:07:21.360Z,1564182441.360 [NAL9602](INFO): GPS fix at 20190726T230715: (36.802481, -121.787039) 2019-07-26T23:07:23.396Z,1564182443.396 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:23.784Z,1564182443.784 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230718.00,A,3648.14874,N,12147.22242,W,0.039,4.98,260719,,,D*70 2019-07-26T23:07:23.786Z,1564182443.786 [NAL9602](INFO): GPS fix at 20190726T230718: (36.802479, -121.787040) 2019-07-26T23:07:26.622Z,1564182446.622 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:27.024Z,1564182447.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230721.00,A,3648.14863,N,12147.22259,W,0.078,4.98,260719,,,D*73 2019-07-26T23:07:27.026Z,1564182447.026 [NAL9602](INFO): GPS fix at 20190726T230721: (36.802477, -121.787043) 2019-07-26T23:07:29.449Z,1564182449.449 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:29.838Z,1564182449.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230724.00,A,3648.14862,N,12147.22278,W,0.097,4.98,260719,,,D*75 2019-07-26T23:07:29.840Z,1564182449.840 [NAL9602](INFO): GPS fix at 20190726T230724: (36.802477, -121.787046) 2019-07-26T23:07:32.681Z,1564182452.681 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:33.076Z,1564182453.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230727.00,A,3648.14860,N,12147.22295,W,0.078,4.98,260719,,,D*76 2019-07-26T23:07:33.090Z,1564182453.090 [NAL9602](INFO): GPS fix at 20190726T230727: (36.802477, -121.787049) 2019-07-26T23:07:35.509Z,1564182455.509 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:35.908Z,1564182455.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230730.00,A,3648.14860,N,12147.22304,W,0.039,4.98,260719,,,D*7C 2019-07-26T23:07:35.910Z,1564182455.910 [NAL9602](INFO): GPS fix at 20190726T230730: (36.802477, -121.787051) 2019-07-26T23:07:38.737Z,1564182458.737 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:39.136Z,1564182459.136 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230733.00,A,3648.14860,N,12147.22307,W,0.078,4.98,260719,,,D*79 2019-07-26T23:07:39.138Z,1564182459.138 [NAL9602](INFO): GPS fix at 20190726T230733: (36.802477, -121.787051) 2019-07-26T23:07:41.569Z,1564182461.569 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:41.964Z,1564182461.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230736.00,A,3648.14858,N,12147.22311,W,0.019,4.98,260719,,,D*77 2019-07-26T23:07:41.966Z,1564182461.966 [NAL9602](INFO): GPS fix at 20190726T230736: (36.802476, -121.787052) 2019-07-26T23:07:44.397Z,1564182464.397 [NAL9602](DEBUG): Fix Requested 2019-07-26T23:07:44.442Z,1564182464.442 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802475 Longitude: -121.787048 2019-07-26T23:07:44.786Z,1564182464.786 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230739.00,A,3648.14864,N,12147.22313,W,0.058,4.98,260719,,,D*70 2019-07-26T23:07:44.788Z,1564182464.788 [NAL9602](INFO): GPS fix at 20190726T230739: (36.802477, -121.787052) 2019-07-26T23:07:44.822Z,1564182464.822 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 0.000000 2019-07-26T23:07:44.822Z,1564182464.822 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2019-07-26T23:07:44.822Z,1564182464.822 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2019-07-26T23:07:45.234Z,1564182465.234 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2019-07-26T23:07:45.234Z,1564182465.234 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2019-07-26T23:07:45.235Z,1564182465.235 [IBIT](IMPORTANT): Pressure:9.734568 PSI 2019-07-26T23:07:45.235Z,1564182465.235 [IBIT](IMPORTANT): Humidity:20.864363 % 2019-07-26T23:07:45.627Z,1564182465.627 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2019-07-26T23:07:45.628Z,1564182465.628 [IBIT](IMPORTANT): buoyancyNeutral: 210.000000 cc 2019-07-26T23:07:45.628Z,1564182465.628 [IBIT](IMPORTANT): massDefault: 1.300000 cm 2019-07-26T23:07:45.628Z,1564182465.628 [IBIT](IMPORTANT): stopDepth: 40.000000 m 2019-07-26T23:07:45.629Z,1564182465.629 [IBIT](IMPORTANT): abortDepth: 50.000000 m 2019-07-26T23:07:45.629Z,1564182465.629 [IBIT](IMPORTANT): IBIT PASSED 2019-07-26T23:08:17.510Z,1564182497.510 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-26T23:09:00.226Z,1564182540.226 [DataOverHttps](IMPORTANT): SBD MTMSN=20190726T230854 2019-07-26T23:09:05.233Z,1564182545.233 [DataOverHttps](INFO): Received command:Restart app 2019-07-26T23:09:05.292Z,1564182545.292 [CommandLine](IMPORTANT): got command restart application 2019-07-26T23:09:06.297Z,1564182546.297 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:06.298Z,1564182546.298 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.313Z,1564182546.313 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-07-26T23:09:06.313Z,1564182546.313 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.314Z,1564182546.314 [CommandLine](INFO): Join timeout helper Thread ID is 6074 2019-07-26T23:09:06.315Z,1564182546.315 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-07-26T23:09:06.315Z,1564182546.315 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.315Z,1564182546.315 [NavChartDb](INFO): Join timeout helper Thread ID is 6075 2019-07-26T23:09:06.577Z,1564182546.577 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:06.578Z,1564182546.578 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.585Z,1564182546.585 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-07-26T23:09:06.585Z,1564182546.585 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.586Z,1564182546.586 [Radio_Surface](INFO): Join timeout helper Thread ID is 6076 2019-07-26T23:09:06.769Z,1564182546.769 [Radio_Surface](INFO): Powering down 2019-07-26T23:09:06.770Z,1564182546.770 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:06.771Z,1564182546.771 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.789Z,1564182546.789 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-07-26T23:09:06.789Z,1564182546.789 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.790Z,1564182546.790 [DataOverHttps](INFO): Join timeout helper Thread ID is 6077 2019-07-26T23:09:06.845Z,1564182546.845 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:06.846Z,1564182546.846 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.861Z,1564182546.861 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-07-26T23:09:06.861Z,1564182546.861 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.862Z,1564182546.862 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6078 2019-07-26T23:09:06.922Z,1564182546.922 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:06.922Z,1564182546.922 [WetLabsBB2FL](INFO): Powering down 2019-07-26T23:09:06.923Z,1564182546.923 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.934Z,1564182546.934 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-07-26T23:09:06.934Z,1564182546.934 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:06.935Z,1564182546.935 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6079 2019-07-26T23:09:07.042Z,1564182547.042 [CTD_Seabird](INFO): Powering down 2019-07-26T23:09:07.053Z,1564182547.053 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:07.053Z,1564182547.053 [CTD_Seabird](INFO): Powering down 2019-07-26T23:09:07.065Z,1564182547.065 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.074Z,1564182547.074 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-07-26T23:09:07.074Z,1564182547.074 [logger ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.075Z,1564182547.075 [logger](INFO): Join timeout helper Thread ID is 6080 2019-07-26T23:09:07.133Z,1564182547.133 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:07.134Z,1564182547.134 [logger ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.154Z,1564182547.154 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-07-26T23:09:07.154Z,1564182547.154 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.154Z,1564182547.154 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-07-26T23:09:07.154Z,1564182547.154 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.155Z,1564182547.155 [controlThread](INFO): Join timeout helper Thread ID is 6081 2019-07-26T23:09:07.181Z,1564182547.181 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T23:09:07.182Z,1564182547.182 [controlThread](DEBUG): Uninitializing ControlThread 2019-07-26T23:09:07.182Z,1564182547.182 [Aanderaa_O2](INFO): Powering down 2019-07-26T23:09:07.203Z,1564182547.203 [AHRS_M2](INFO): Powering down 2019-07-26T23:09:07.274Z,1564182547.274 [NAL9602](INFO): Powering down 2019-07-26T23:09:07.275Z,1564182547.275 [DAT](INFO): Powering down 2019-07-26T23:09:07.394Z,1564182547.394 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-07-26T23:09:07.395Z,1564182547.395 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-07-26T23:09:07.395Z,1564182547.395 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-07-26T23:09:07.396Z,1564182547.396 [MissionManager](INFO): Uninitializing Mission Default 2019-07-26T23:09:07.396Z,1564182547.396 [Default] Stopped 2019-07-26T23:09:07.396Z,1564182547.396 [Default](DEBUG): Aggregate::uninitialize Default 2019-07-26T23:09:07.396Z,1564182547.396 [Default:B.GoToSurface] Stopped 2019-07-26T23:09:07.396Z,1564182547.396 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-07-26T23:09:07.396Z,1564182547.396 [Default:CheckIn] Stopped 2019-07-26T23:09:07.397Z,1564182547.397 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T23:09:07.397Z,1564182547.397 [Default:CheckIn:C.Wait] Stopped 2019-07-26T23:09:07.397Z,1564182547.397 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T23:09:07.399Z,1564182547.399 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-07-26T23:09:07.399Z,1564182547.399 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-07-26T23:09:07.400Z,1564182547.400 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-07-26T23:09:07.400Z,1564182547.400 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-07-26T23:09:07.400Z,1564182547.400 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-07-26T23:09:07.400Z,1564182547.400 [BuoyancyServo](INFO): Powering down 2019-07-26T23:09:07.413Z,1564182547.413 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-07-26T23:09:07.414Z,1564182547.414 [ElevatorServo](INFO): Powering down 2019-07-26T23:09:07.414Z,1564182547.414 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-07-26T23:09:07.414Z,1564182547.414 [MassServo](INFO): Powering down 2019-07-26T23:09:07.415Z,1564182547.415 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-07-26T23:09:07.415Z,1564182547.415 [RudderServo](INFO): Powering down 2019-07-26T23:09:07.416Z,1564182547.416 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-07-26T23:09:07.416Z,1564182547.416 [ThrusterServo](INFO): Powering down 2019-07-26T23:09:07.417Z,1564182547.417 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-07-26T23:09:07.418Z,1564182547.418 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-07-26T23:09:07.418Z,1564182547.418 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-07-26T23:09:07.418Z,1564182547.418 [CBIT](DEBUG): Powering off loads. 2019-07-26T23:09:07.429Z,1564182547.429 [CBIT](DEBUG): Disabling WDT. 2019-07-26T23:09:07.441Z,1564182547.441 [CBIT](DEBUG): Opening all GF detection circuits. 2019-07-26T23:09:07.442Z,1564182547.442 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.484Z,1564182547.484 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.489Z,1564182547.489 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.552Z,1564182547.552 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.557Z,1564182547.557 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.607Z,1564182547.607 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-26T23:09:07.676Z,1564182547.676 [logger ThreadHandler](INFO): Thread cancelled.